builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1474517723.28 results: success (0) buildid: 20160921181859 builduid: 275328f5aaa44fad95451c7dba401719 revision: f93ac29ad106222f2cd7e55a1d24795f89425dd4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 21:15:23.282611) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 21:15:23.283112) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 21:15:23.283428) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 21:15:23.298156) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 21:15:23.298453) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-21 21:15:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-09-21 21:15:24 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091862 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 21:15:23.407360) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 21:15:23.408195) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014570 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 21:15:23.458522) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 21:15:23.458893) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f93ac29ad106222f2cd7e55a1d24795f89425dd4 --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 f93ac29ad106222f2cd7e55a1d24795f89425dd4 --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=d6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-21 21:15:24,220 truncating revision to first 12 chars 2016-09-21 21:15:24,220 Setting DEBUG logging. 2016-09-21 21:15:24,220 attempt 1/10 2016-09-21 21:15:24,220 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f93ac29ad106?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 21:15:24,524 unpacking tar archive at: mozilla-aurora-f93ac29ad106/testing/mozharness/ program finished with exit code 0 elapsedTime=0.658859 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 21:15:24.133802) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 21:15:24.134717) ========= script_repo_revision: f93ac29ad106222f2cd7e55a1d24795f89425dd4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 21:15:24.135972) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 21:15:24.136664) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 21:15:24.148266) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-09-21 21:15:24.148574) ========= /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=d6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:15:24 INFO - MultiFileLogger online at 20160921 21:15:24 in /builds/slave/test 21:15:24 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 21:15:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:15:24 INFO - {'append_to_log': False, 21:15:24 INFO - 'base_work_dir': '/builds/slave/test', 21:15:24 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:15:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:15:24 INFO - 'branch': 'Mozilla-Aurora', 21:15:24 INFO - 'buildbot_json_path': 'buildprops.json', 21:15:24 INFO - 'config_files': ('talos/linux_config.py',), 21:15:24 INFO - 'default_actions': ('clobber', 21:15:24 INFO - 'read-buildbot-config', 21:15:24 INFO - 'download-and-extract', 21:15:24 INFO - 'populate-webroot', 21:15:24 INFO - 'create-virtualenv', 21:15:24 INFO - 'install', 21:15:24 INFO - 'run-tests'), 21:15:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:15:24 INFO - 'download_minidump_stackwalk': True, 21:15:24 INFO - 'download_symbols': 'ondemand', 21:15:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:15:24 INFO - 'tooltool.py': '/tools/tooltool.py', 21:15:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:15:24 INFO - '/tools/misc-python/virtualenv.py')}, 21:15:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:15:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:15:24 INFO - 'installer_path': 'installer.exe', 21:15:24 INFO - 'log_level': 'info', 21:15:24 INFO - 'log_name': 'talos', 21:15:24 INFO - 'log_to_console': True, 21:15:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:15:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:15:24 INFO - 'opt_config_files': (), 21:15:24 INFO - 'pip_index': False, 21:15:24 INFO - 'sps_profile': False, 21:15:24 INFO - 'sps_profile_interval': 0, 21:15:24 INFO - 'suite': 'dromaeojs', 21:15:24 INFO - 'system_bits': '32', 21:15:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:15:24 INFO - 'title': 'talos-linux64-ix-034', 21:15:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:15:24 INFO - 'use_talos_json': True, 21:15:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:15:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:15:24 INFO - 'work_dir': 'build'} 21:15:24 INFO - [mozharness: 2016-09-22 04:15:24.997964Z] Running clobber step. 21:15:24 INFO - Running pre-action listener: _resource_record_pre_action 21:15:24 INFO - Running main action method: clobber 21:15:24 INFO - rmtree: /builds/slave/test/build 21:15:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:15:25 INFO - Running post-action listener: _resource_record_post_action 21:15:25 INFO - [mozharness: 2016-09-22 04:15:25.469842Z] Finished clobber step (success) 21:15:25 INFO - [mozharness: 2016-09-22 04:15:25.469944Z] Running read-buildbot-config step. 21:15:25 INFO - Running pre-action listener: _resource_record_pre_action 21:15:25 INFO - Running main action method: read_buildbot_config 21:15:25 INFO - Using buildbot properties: 21:15:25 INFO - { 21:15:25 INFO - "project": "", 21:15:25 INFO - "product": "firefox", 21:15:25 INFO - "script_repo_revision": "production", 21:15:25 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 21:15:25 INFO - "repository": "", 21:15:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 21:15:25 INFO - "buildid": "20160921181859", 21:15:25 INFO - "pgo_build": "True", 21:15:25 INFO - "basedir": "/builds/slave/test", 21:15:25 INFO - "buildnumber": 776, 21:15:25 INFO - "slavename": "talos-linux64-ix-034", 21:15:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:15:25 INFO - "platform": "ubuntu64_hw", 21:15:25 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 21:15:25 INFO - "branch": "mozilla-aurora", 21:15:25 INFO - "repo_path": "releases/mozilla-aurora", 21:15:25 INFO - "revision": "f93ac29ad106222f2cd7e55a1d24795f89425dd4", 21:15:25 INFO - "stage_platform": "linux64-pgo", 21:15:25 INFO - "builduid": "275328f5aaa44fad95451c7dba401719", 21:15:25 INFO - "slavebuilddir": "test" 21:15:25 INFO - } 21:15:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 21:15:25 INFO - Running post-action listener: _resource_record_post_action 21:15:25 INFO - [mozharness: 2016-09-22 04:15:25.472467Z] Finished read-buildbot-config step (success) 21:15:25 INFO - [mozharness: 2016-09-22 04:15:25.472558Z] Running download-and-extract step. 21:15:25 INFO - Running pre-action listener: _resource_record_pre_action 21:15:25 INFO - Running main action method: download_and_extract 21:15:25 INFO - mkdir: /builds/slave/test/build/tests 21:15:25 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')]} 21:15:25 INFO - trying https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 21:15:25 INFO - Downloading https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/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 21:15:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/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 21:15:26 INFO - Downloaded 1361 bytes. 21:15:26 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 21:15:26 INFO - Using the following test package requirements: 21:15:26 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 21:15:26 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 21:15:26 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 21:15:26 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 21:15:26 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 21:15:26 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 21:15:26 INFO - u'jsshell-linux-x86_64.zip'], 21:15:26 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 21:15:26 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 21:15:26 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 21:15:26 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 21:15:26 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 21:15:26 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 21:15:26 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 21:15:26 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 21:15:26 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 21:15:26 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 21:15:26 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 21:15:26 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:15:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 21:15:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:15:26 INFO - Fetch https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 21:15:26 INFO - Expected file size: 21893474 21:15:30 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 21:15:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 21:15:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:15:30 INFO - Fetch https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 21:15:31 INFO - Expected file size: 21893474 21:15:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 21:15:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:15:36 INFO - Fetch https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 21:15:36 INFO - Expected file size: 14414059 21:15:37 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')]} 21:15:37 INFO - trying https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 21:15:37 INFO - Downloading https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/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 21:15:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/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 21:15:38 INFO - Downloaded 63946037 bytes. 21:15:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 21:15:38 INFO - mkdir: /builds/slave/test/properties 21:15:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:15:38 INFO - Writing to file /builds/slave/test/properties/build_url 21:15:38 INFO - Contents: 21:15:38 INFO - build_url:https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 21:15:39 INFO - Running post-action listener: _resource_record_post_action 21:15:39 INFO - Running post-action listener: set_extra_try_arguments 21:15:39 INFO - [mozharness: 2016-09-22 04:15:39.214031Z] Finished download-and-extract step (success) 21:15:39 INFO - [mozharness: 2016-09-22 04:15:39.214260Z] Running populate-webroot step. 21:15:39 INFO - Running pre-action listener: _resource_record_pre_action 21:15:39 INFO - Running main action method: populate_webroot 21:15:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:15:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:15:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:15:39 INFO - '--tppagecycles', 21:15:39 INFO - '7'], 21:15:39 INFO - 'tests': ['tsvgm']}}, 21:15:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:15:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:15:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:15:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:15:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:15:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:15:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:39 INFO - 'talos_options': ['--disable-e10s'], 21:15:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:15:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:15:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:39 INFO - 'talos_options': ['--disable-e10s'], 21:15:39 INFO - 'tests': ['damp', 'tps']}, 21:15:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:39 INFO - 'tests': ['damp', 'tps']}, 21:15:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:15:39 INFO - 'tests': ['dromaeo_dom']}, 21:15:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:15:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:15:39 INFO - 'tests': ['basic_compositor_video']}, 21:15:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:15:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:15:39 INFO - 'tests': ['a11yr', 21:15:39 INFO - 'ts_paint', 21:15:39 INFO - 'tpaint', 21:15:39 INFO - 'sessionrestore', 21:15:39 INFO - 'sessionrestore_no_auto_restore', 21:15:39 INFO - 'tabpaint']}, 21:15:39 INFO - 'other-e10s': {'tests': ['a11yr', 21:15:39 INFO - 'ts_paint', 21:15:39 INFO - 'tpaint', 21:15:39 INFO - 'sessionrestore', 21:15:39 INFO - 'sessionrestore_no_auto_restore', 21:15:39 INFO - 'tabpaint']}, 21:15:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:15:39 INFO - 'ts_paint', 21:15:39 INFO - 'tpaint', 21:15:39 INFO - 'sessionrestore', 21:15:39 INFO - 'sessionrestore_no_auto_restore', 21:15:39 INFO - 'tabpaint']}, 21:15:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:15:39 INFO - 'ts_paint', 21:15:39 INFO - 'tpaint', 21:15:39 INFO - 'sessionrestore', 21:15:39 INFO - 'sessionrestore_no_auto_restore', 21:15:39 INFO - 'tabpaint']}, 21:15:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:15:39 INFO - 'tests': ['a11yr', 21:15:39 INFO - 'ts_paint', 21:15:39 INFO - 'tpaint', 21:15:39 INFO - 'sessionrestore', 21:15:39 INFO - 'sessionrestore_no_auto_restore', 21:15:39 INFO - 'tabpaint']}, 21:15:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:15:39 INFO - 'tests': ['a11yr', 21:15:39 INFO - 'ts_paint', 21:15:39 INFO - 'tpaint', 21:15:39 INFO - 'sessionrestore', 21:15:39 INFO - 'sessionrestore_no_auto_restore', 21:15:39 INFO - 'tabpaint']}, 21:15:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:15:39 INFO - 'tests': ['tsvgx', 21:15:39 INFO - 'tsvgr_opacity', 21:15:39 INFO - 'tart', 21:15:39 INFO - 'tscrollx', 21:15:39 INFO - 'cart']}, 21:15:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:15:39 INFO - 'tsvgr_opacity', 21:15:39 INFO - 'tart', 21:15:39 INFO - 'tscrollx', 21:15:39 INFO - 'cart']}, 21:15:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:39 INFO - 'talos_options': ['--disable-e10s'], 21:15:39 INFO - 'tests': ['tp5o']}, 21:15:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:39 INFO - 'tests': ['tp5o']}, 21:15:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:39 INFO - 'talos_options': ['--disable-e10s', 21:15:39 INFO - '--xperf_path', 21:15:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:15:39 INFO - 'tests': ['tp5n']}, 21:15:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:39 INFO - 'talos_options': ['--xperf_path', 21:15:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:15:39 INFO - 'tests': ['tp5n']}}, 21:15:39 INFO - 'talos.zip': {'path': '', 21:15:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:15:39 INFO - Running post-action listener: _resource_record_post_action 21:15:39 INFO - [mozharness: 2016-09-22 04:15:39.236075Z] Finished populate-webroot step (success) 21:15:39 INFO - [mozharness: 2016-09-22 04:15:39.236164Z] Running create-virtualenv step. 21:15:39 INFO - Running pre-action listener: _resource_record_pre_action 21:15:39 INFO - Running main action method: create_virtualenv 21:15:39 INFO - Creating virtualenv /builds/slave/test/build/venv 21:15:39 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 21:15:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:15:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:15:39 INFO - Using real prefix '/usr' 21:15:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:15:39 INFO - Installing distribute.............................................................................................................................................................................................done. 21:15:41 INFO - Installing pip.................done. 21:15:41 INFO - Return code: 0 21:15:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:15:41 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')]} 21:15:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:41 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')]} 21:15:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:41 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 0x7f217c7ccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d2f60>, '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 0x14d3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d41d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', '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 21:15:41 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 21:15:41 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 21:15:41 INFO - Using env: {'DISPLAY': ':0', 21:15:41 INFO - 'HOME': '/home/cltbld', 21:15:41 INFO - 'LANG': 'en_US.UTF-8', 21:15:41 INFO - 'LANGUAGE': 'en_US:en', 21:15:41 INFO - 'LOGNAME': 'cltbld', 21:15:41 INFO - 'MAIL': '/var/mail/cltbld', 21:15:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:41 INFO - 'MOZ_NO_REMOTE': '1', 21:15:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:15:41 INFO - 'NO_EM_RESTART': '1', 21:15:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:15:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:41 INFO - 'PWD': '/builds/slave/test', 21:15:41 INFO - 'SHELL': '/bin/bash', 21:15:41 INFO - 'SHLVL': '1', 21:15:41 INFO - 'TERM': 'linux', 21:15:41 INFO - 'TMOUT': '86400', 21:15:41 INFO - 'USER': 'cltbld', 21:15:41 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', 21:15:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:41 INFO - '_': '/tools/buildbot/bin/python'} 21:15:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:42 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 21:15:42 INFO - Cleaning up... 21:15:42 INFO - Return code: 0 21:15:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:15:42 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')]} 21:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:42 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')]} 21:15:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:42 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 0x7f217c7ccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d2f60>, '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 0x14d3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d41d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', '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 21:15:42 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 21:15:42 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 21:15:42 INFO - Using env: {'DISPLAY': ':0', 21:15:42 INFO - 'HOME': '/home/cltbld', 21:15:42 INFO - 'LANG': 'en_US.UTF-8', 21:15:42 INFO - 'LANGUAGE': 'en_US:en', 21:15:42 INFO - 'LOGNAME': 'cltbld', 21:15:42 INFO - 'MAIL': '/var/mail/cltbld', 21:15:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:42 INFO - 'MOZ_NO_REMOTE': '1', 21:15:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:15:42 INFO - 'NO_EM_RESTART': '1', 21:15:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:15:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:42 INFO - 'PWD': '/builds/slave/test', 21:15:42 INFO - 'SHELL': '/bin/bash', 21:15:42 INFO - 'SHLVL': '1', 21:15:42 INFO - 'TERM': 'linux', 21:15:42 INFO - 'TMOUT': '86400', 21:15:42 INFO - 'USER': 'cltbld', 21:15:42 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', 21:15:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:42 INFO - '_': '/tools/buildbot/bin/python'} 21:15:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:42 INFO - Downloading/unpacking psutil>=3.1.1 21:15:42 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 21:15:42 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 21:15:42 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 21:15:42 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 21:15:42 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 21:15:42 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 21:15:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:15:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:15:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:15:44 INFO - Installing collected packages: psutil 21:15:44 INFO - Running setup.py install for psutil 21:15:44 INFO - building 'psutil._psutil_linux' extension 21:15:44 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 21:15:44 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 21:15:44 INFO - building 'psutil._psutil_posix' extension 21:15:44 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 21:15:44 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 21:15:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:15:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:15:44 INFO - Successfully installed psutil 21:15:44 INFO - Cleaning up... 21:15:44 INFO - Return code: 0 21:15:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:15:44 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')]} 21:15:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:44 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')]} 21:15:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:44 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 0x7f217c7ccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d2f60>, '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 0x14d3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d41d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', '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 21:15:44 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 21:15:44 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 21:15:44 INFO - Using env: {'DISPLAY': ':0', 21:15:44 INFO - 'HOME': '/home/cltbld', 21:15:44 INFO - 'LANG': 'en_US.UTF-8', 21:15:44 INFO - 'LANGUAGE': 'en_US:en', 21:15:44 INFO - 'LOGNAME': 'cltbld', 21:15:44 INFO - 'MAIL': '/var/mail/cltbld', 21:15:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:44 INFO - 'MOZ_NO_REMOTE': '1', 21:15:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:15:44 INFO - 'NO_EM_RESTART': '1', 21:15:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:15:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:44 INFO - 'PWD': '/builds/slave/test', 21:15:44 INFO - 'SHELL': '/bin/bash', 21:15:44 INFO - 'SHLVL': '1', 21:15:44 INFO - 'TERM': 'linux', 21:15:44 INFO - 'TMOUT': '86400', 21:15:44 INFO - 'USER': 'cltbld', 21:15:44 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', 21:15:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:44 INFO - '_': '/tools/buildbot/bin/python'} 21:15:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:15:45 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 21:15:45 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 21:15:45 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 21:15:45 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 21:15:45 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 21:15:45 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 21:15:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:15:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:15:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:15:46 INFO - Installing collected packages: mozsystemmonitor 21:15:46 INFO - Running setup.py install for mozsystemmonitor 21:15:47 INFO - Successfully installed mozsystemmonitor 21:15:47 INFO - Cleaning up... 21:15:47 INFO - Return code: 0 21:15:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:15:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f217c7ccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d2f60>, '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 0x14d3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d41d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', '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 21:15:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:15:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:15:47 INFO - Using env: {'DISPLAY': ':0', 21:15:47 INFO - 'HOME': '/home/cltbld', 21:15:47 INFO - 'LANG': 'en_US.UTF-8', 21:15:47 INFO - 'LANGUAGE': 'en_US:en', 21:15:47 INFO - 'LOGNAME': 'cltbld', 21:15:47 INFO - 'MAIL': '/var/mail/cltbld', 21:15:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:47 INFO - 'MOZ_NO_REMOTE': '1', 21:15:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:15:47 INFO - 'NO_EM_RESTART': '1', 21:15:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:15:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:47 INFO - 'PWD': '/builds/slave/test', 21:15:47 INFO - 'SHELL': '/bin/bash', 21:15:47 INFO - 'SHLVL': '1', 21:15:47 INFO - 'TERM': 'linux', 21:15:47 INFO - 'TMOUT': '86400', 21:15:47 INFO - 'USER': 'cltbld', 21:15:47 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', 21:15:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:47 INFO - '_': '/tools/buildbot/bin/python'} 21:15:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:47 INFO - Downloading/unpacking jsonschema==2.5.1 21:15:47 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 21:15:47 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 21:15:47 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 21:15:47 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 21:15:47 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 21:15:47 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 21:15:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:15:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:15:49 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 21:15:49 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 21:15:49 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 21:15:49 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 21:15:49 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 21:15:49 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 21:15:49 INFO - Downloading functools32-3.2.3-2.tar.gz 21:15:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:15:49 INFO - warning: no files found matching '*.txt' 21:15:49 INFO - no previously-included directories found matching 'build' 21:15:49 INFO - no previously-included directories found matching 'dist' 21:15:49 INFO - no previously-included directories found matching '.git*' 21:15:49 INFO - Installing collected packages: jsonschema, functools32 21:15:49 INFO - Running setup.py install for functools32 21:15:49 INFO - warning: no files found matching '*.txt' 21:15:49 INFO - no previously-included directories found matching 'build' 21:15:49 INFO - no previously-included directories found matching 'dist' 21:15:49 INFO - no previously-included directories found matching '.git*' 21:15:49 INFO - Successfully installed jsonschema functools32 21:15:49 INFO - Cleaning up... 21:15:49 INFO - Return code: 0 21:15:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:15:49 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')]} 21:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:49 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')]} 21:15:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:49 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 0x7f217c7ccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d2f60>, '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 0x14d3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d41d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', '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 21:15:49 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 21:15:49 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 21:15:49 INFO - Using env: {'DISPLAY': ':0', 21:15:49 INFO - 'HOME': '/home/cltbld', 21:15:49 INFO - 'LANG': 'en_US.UTF-8', 21:15:49 INFO - 'LANGUAGE': 'en_US:en', 21:15:49 INFO - 'LOGNAME': 'cltbld', 21:15:49 INFO - 'MAIL': '/var/mail/cltbld', 21:15:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:49 INFO - 'MOZ_NO_REMOTE': '1', 21:15:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:15:49 INFO - 'NO_EM_RESTART': '1', 21:15:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:15:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:49 INFO - 'PWD': '/builds/slave/test', 21:15:49 INFO - 'SHELL': '/bin/bash', 21:15:49 INFO - 'SHLVL': '1', 21:15:49 INFO - 'TERM': 'linux', 21:15:49 INFO - 'TMOUT': '86400', 21:15:49 INFO - 'USER': 'cltbld', 21:15:49 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', 21:15:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:49 INFO - '_': '/tools/buildbot/bin/python'} 21:15:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:15:49 INFO - Cleaning up... 21:15:49 INFO - Return code: 0 21:15:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:15:49 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')]} 21:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:49 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')]} 21:15:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:49 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 0x7f217c7ccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d2f60>, '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 0x14d3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d41d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', '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 21:15:49 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 21:15:49 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 21:15:49 INFO - Using env: {'DISPLAY': ':0', 21:15:49 INFO - 'HOME': '/home/cltbld', 21:15:49 INFO - 'LANG': 'en_US.UTF-8', 21:15:49 INFO - 'LANGUAGE': 'en_US:en', 21:15:49 INFO - 'LOGNAME': 'cltbld', 21:15:49 INFO - 'MAIL': '/var/mail/cltbld', 21:15:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:49 INFO - 'MOZ_NO_REMOTE': '1', 21:15:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:15:49 INFO - 'NO_EM_RESTART': '1', 21:15:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:15:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:49 INFO - 'PWD': '/builds/slave/test', 21:15:49 INFO - 'SHELL': '/bin/bash', 21:15:49 INFO - 'SHLVL': '1', 21:15:49 INFO - 'TERM': 'linux', 21:15:49 INFO - 'TMOUT': '86400', 21:15:49 INFO - 'USER': 'cltbld', 21:15:49 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', 21:15:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:49 INFO - '_': '/tools/buildbot/bin/python'} 21:15:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:50 INFO - Downloading/unpacking blobuploader==1.2.4 21:15:50 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 21:15:50 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 21:15:50 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 21:15:50 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 21:15:50 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 21:15:50 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 21:15:52 INFO - Downloading blobuploader-1.2.4.tar.gz 21:15:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:15:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:15:52 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 21:15:52 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 21:15:52 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 21:15:52 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 21:15:52 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 21:15:52 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 21:15:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:15:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:15:52 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 21:15:52 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 21:15:52 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 21:15:52 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 21:15:52 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 21:15:52 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 21:15:52 INFO - Downloading docopt-0.6.1.tar.gz 21:15:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:15:52 INFO - Installing collected packages: blobuploader, requests, docopt 21:15:52 INFO - Running setup.py install for blobuploader 21:15:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:15:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:15:52 INFO - Running setup.py install for requests 21:15:53 INFO - Running setup.py install for docopt 21:15:53 INFO - Successfully installed blobuploader requests docopt 21:15:53 INFO - Cleaning up... 21:15:53 INFO - Return code: 0 21:15:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:15:53 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')]} 21:15:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:53 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')]} 21:15:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:53 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 0x7f217c7ccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d2f60>, '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 0x14d3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d41d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', '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 21:15:53 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 21:15:53 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 21:15:53 INFO - Using env: {'DISPLAY': ':0', 21:15:53 INFO - 'HOME': '/home/cltbld', 21:15:53 INFO - 'LANG': 'en_US.UTF-8', 21:15:53 INFO - 'LANGUAGE': 'en_US:en', 21:15:53 INFO - 'LOGNAME': 'cltbld', 21:15:53 INFO - 'MAIL': '/var/mail/cltbld', 21:15:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:53 INFO - 'MOZ_NO_REMOTE': '1', 21:15:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:15:53 INFO - 'NO_EM_RESTART': '1', 21:15:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:15:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:53 INFO - 'PWD': '/builds/slave/test', 21:15:53 INFO - 'SHELL': '/bin/bash', 21:15:53 INFO - 'SHLVL': '1', 21:15:53 INFO - 'TERM': 'linux', 21:15:53 INFO - 'TMOUT': '86400', 21:15:53 INFO - 'USER': 'cltbld', 21:15:53 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', 21:15:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:53 INFO - '_': '/tools/buildbot/bin/python'} 21:15:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:15:53 INFO - Running setup.py (path:/tmp/pip-W5quBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:15:53 INFO - Running setup.py (path:/tmp/pip-gAXmx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:15:53 INFO - Running setup.py (path:/tmp/pip-RF563C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:15:53 INFO - Running setup.py (path:/tmp/pip-kVqWHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:15:53 INFO - Running setup.py (path:/tmp/pip-1bumwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:15:53 INFO - Running setup.py (path:/tmp/pip-B4jJ4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:15:54 INFO - Running setup.py (path:/tmp/pip-UKy6i2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:15:54 INFO - Running setup.py (path:/tmp/pip-ocNafZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:15:54 INFO - Running setup.py (path:/tmp/pip-sGxnON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:15:54 INFO - Running setup.py (path:/tmp/pip-Oe_FDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:15:54 INFO - Running setup.py (path:/tmp/pip-u9q6Mo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:15:54 INFO - Running setup.py (path:/tmp/pip-3uDZAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:15:54 INFO - Running setup.py (path:/tmp/pip-gtC4TO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:15:54 INFO - Running setup.py (path:/tmp/pip-NR329M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:15:54 INFO - Running setup.py (path:/tmp/pip-hJKN9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:15:54 INFO - Running setup.py (path:/tmp/pip-XX8n_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:15:54 INFO - Running setup.py (path:/tmp/pip-Yd3lnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:15:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:15:54 INFO - Running setup.py install for manifestparser 21:15:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:15:54 INFO - Running setup.py install for mozcrash 21:15:55 INFO - Running setup.py install for mozdebug 21:15:55 INFO - Running setup.py install for mozdevice 21:15:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:15:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:15:55 INFO - Running setup.py install for mozfile 21:15:55 INFO - Running setup.py install for mozhttpd 21:15:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:15:55 INFO - Running setup.py install for mozinfo 21:15:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:15:55 INFO - Running setup.py install for mozInstall 21:15:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:15:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:15:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:15:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:15:55 INFO - Running setup.py install for mozleak 21:15:55 INFO - Running setup.py install for mozlog 21:15:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:15:55 INFO - Running setup.py install for moznetwork 21:15:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:15:55 INFO - Running setup.py install for mozprocess 21:15:56 INFO - Running setup.py install for mozprofile 21:15:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:15:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:15:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:15:56 INFO - Running setup.py install for mozrunner 21:15:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:15:56 INFO - Running setup.py install for mozscreenshot 21:15:56 INFO - Running setup.py install for moztest 21:15:56 INFO - Running setup.py install for mozversion 21:15:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:15:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:15:56 INFO - Cleaning up... 21:15:56 INFO - Return code: 0 21:15:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:56 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 0x7f217c7ccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d2f60>, '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 0x14d3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d41d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', '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 21:15:56 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 21:15:56 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 21:15:56 INFO - Using env: {'DISPLAY': ':0', 21:15:56 INFO - 'HOME': '/home/cltbld', 21:15:56 INFO - 'LANG': 'en_US.UTF-8', 21:15:56 INFO - 'LANGUAGE': 'en_US:en', 21:15:56 INFO - 'LOGNAME': 'cltbld', 21:15:56 INFO - 'MAIL': '/var/mail/cltbld', 21:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:56 INFO - 'MOZ_NO_REMOTE': '1', 21:15:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:15:56 INFO - 'NO_EM_RESTART': '1', 21:15:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:56 INFO - 'PWD': '/builds/slave/test', 21:15:56 INFO - 'SHELL': '/bin/bash', 21:15:56 INFO - 'SHLVL': '1', 21:15:56 INFO - 'TERM': 'linux', 21:15:56 INFO - 'TMOUT': '86400', 21:15:56 INFO - 'USER': 'cltbld', 21:15:56 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', 21:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:56 INFO - '_': '/tools/buildbot/bin/python'} 21:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:15:56 INFO - Running setup.py (path:/tmp/pip-x3rLbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:15:56 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)) 21:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:15:56 INFO - Running setup.py (path:/tmp/pip-HSVlp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:15:57 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)) 21:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:15:57 INFO - Running setup.py (path:/tmp/pip-l5IjhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:15:57 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)) 21:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:15:57 INFO - Running setup.py (path:/tmp/pip-ZdEqI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:15:57 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)) 21:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:15:57 INFO - Running setup.py (path:/tmp/pip-p2k8SO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:15:57 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)) 21:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:15:57 INFO - Running setup.py (path:/tmp/pip-CFAHJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:15:57 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)) 21:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:15:57 INFO - Running setup.py (path:/tmp/pip-YGM8Ta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:15:57 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)) 21:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:15:57 INFO - Running setup.py (path:/tmp/pip-SzpIVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:15:57 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)) 21:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:15:57 INFO - Running setup.py (path:/tmp/pip-XneBm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:15:57 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)) 21:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:15:57 INFO - Running setup.py (path:/tmp/pip-hOz1LK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:15:57 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)) 21:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:15:57 INFO - Running setup.py (path:/tmp/pip-uYXl21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:15:57 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)) 21:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:15:57 INFO - Running setup.py (path:/tmp/pip-SimkoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:15:57 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)) 21:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:15:57 INFO - Running setup.py (path:/tmp/pip-CGyZOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:15:57 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)) 21:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:15:57 INFO - Running setup.py (path:/tmp/pip-XpbWxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:15:57 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)) 21:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:15:57 INFO - Running setup.py (path:/tmp/pip-VR0b2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:15:57 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)) 21:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:15:57 INFO - Running setup.py (path:/tmp/pip-DrJSYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:15:58 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)) 21:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:15:58 INFO - Running setup.py (path:/tmp/pip-bCT_49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:15:58 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)) 21:15:58 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)) 21:15:58 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)) 21:15:58 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)) 21:15:58 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)) 21:15:58 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)) 21:15:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:15:58 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 21:15:58 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 21:15:58 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 21:15:58 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 21:15:58 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 21:15:58 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 21:15:59 INFO - Downloading blessings-1.6.tar.gz 21:15:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:16:00 INFO - Installing collected packages: blessings 21:16:00 INFO - Running setup.py install for blessings 21:16:00 INFO - Successfully installed blessings 21:16:00 INFO - Cleaning up... 21:16:00 INFO - Return code: 0 21:16:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:16:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:16:00 INFO - Reading from file tmpfile_stdout 21:16:00 INFO - Current package versions: 21:16:00 INFO - argparse == 1.2.1 21:16:00 INFO - blessings == 1.6 21:16:00 INFO - blobuploader == 1.2.4 21:16:00 INFO - docopt == 0.6.1 21:16:00 INFO - functools32 == 3.2.3-2 21:16:00 INFO - jsonschema == 2.5.1 21:16:00 INFO - manifestparser == 1.1 21:16:00 INFO - mozInstall == 1.12 21:16:00 INFO - mozcrash == 0.17 21:16:00 INFO - mozdebug == 0.1 21:16:00 INFO - mozdevice == 0.48 21:16:00 INFO - mozfile == 1.2 21:16:00 INFO - mozhttpd == 0.7 21:16:00 INFO - mozinfo == 0.9 21:16:00 INFO - mozleak == 0.1 21:16:00 INFO - mozlog == 3.4 21:16:00 INFO - moznetwork == 0.27 21:16:00 INFO - mozprocess == 0.23 21:16:00 INFO - mozprofile == 0.28 21:16:00 INFO - mozrunner == 6.12 21:16:00 INFO - mozscreenshot == 0.1 21:16:00 INFO - mozsystemmonitor == 0.3 21:16:00 INFO - moztest == 0.7 21:16:00 INFO - mozversion == 1.4 21:16:00 INFO - psutil == 3.1.1 21:16:00 INFO - requests == 1.2.3 21:16:00 INFO - wsgiref == 0.1.2 21:16:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:16: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')]} 21:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16: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')]} 21:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16:00 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 0x7f217c7ccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d2f60>, '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 0x14d3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d41d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', '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 21:16:00 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 21:16:00 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 21:16:00 INFO - Using env: {'DISPLAY': ':0', 21:16:00 INFO - 'HOME': '/home/cltbld', 21:16:00 INFO - 'LANG': 'en_US.UTF-8', 21:16:00 INFO - 'LANGUAGE': 'en_US:en', 21:16:00 INFO - 'LOGNAME': 'cltbld', 21:16:00 INFO - 'MAIL': '/var/mail/cltbld', 21:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:00 INFO - 'MOZ_NO_REMOTE': '1', 21:16:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:00 INFO - 'NO_EM_RESTART': '1', 21:16:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:00 INFO - 'PWD': '/builds/slave/test', 21:16:00 INFO - 'SHELL': '/bin/bash', 21:16:00 INFO - 'SHLVL': '1', 21:16:00 INFO - 'TERM': 'linux', 21:16:00 INFO - 'TMOUT': '86400', 21:16:00 INFO - 'USER': 'cltbld', 21:16:00 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', 21:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:00 INFO - '_': '/tools/buildbot/bin/python'} 21:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:00 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)) 21:16:00 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)) 21:16:00 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)) 21:16:00 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)) 21:16:00 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)) 21:16:00 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)) 21:16:00 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)) 21:16:00 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)) 21:16:00 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)) 21:16:00 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)) 21:16:00 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)) 21:16:00 INFO - Cleaning up... 21:16:00 INFO - Return code: 0 21:16:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:16: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')]} 21:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16: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')]} 21:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:16: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f217c7ccf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14d2f60>, '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 0x14d3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d41d0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', '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 21:16: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', 'jsonschema'] in /builds/slave/test/build 21:16: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 jsonschema 21:16:00 INFO - Using env: {'DISPLAY': ':0', 21:16:00 INFO - 'HOME': '/home/cltbld', 21:16:00 INFO - 'LANG': 'en_US.UTF-8', 21:16:00 INFO - 'LANGUAGE': 'en_US:en', 21:16:00 INFO - 'LOGNAME': 'cltbld', 21:16:00 INFO - 'MAIL': '/var/mail/cltbld', 21:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:00 INFO - 'MOZ_NO_REMOTE': '1', 21:16:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:00 INFO - 'NO_EM_RESTART': '1', 21:16:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:00 INFO - 'PWD': '/builds/slave/test', 21:16:00 INFO - 'SHELL': '/bin/bash', 21:16:00 INFO - 'SHLVL': '1', 21:16:00 INFO - 'TERM': 'linux', 21:16:00 INFO - 'TMOUT': '86400', 21:16:00 INFO - 'USER': 'cltbld', 21:16:00 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', 21:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:00 INFO - '_': '/tools/buildbot/bin/python'} 21:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:16:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:16:00 INFO - Cleaning up... 21:16:00 INFO - Return code: 0 21:16:00 INFO - Running post-action listener: _resource_record_post_action 21:16:00 INFO - Running post-action listener: _start_resource_monitoring 21:16:00 INFO - Starting resource monitoring. 21:16:00 INFO - [mozharness: 2016-09-22 04:16:00.917559Z] Finished create-virtualenv step (success) 21:16:00 INFO - [mozharness: 2016-09-22 04:16:00.918019Z] Running install step. 21:16:00 INFO - Running pre-action listener: _resource_record_pre_action 21:16:00 INFO - Running main action method: install 21:16:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:16:01 INFO - Reading from file tmpfile_stdout 21:16:01 INFO - Detecting whether we're running mozinstall >=1.0... 21:16:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:16:01 INFO - Reading from file tmpfile_stdout 21:16:01 INFO - Output received: 21:16:01 INFO - Usage: mozinstall [options] installer 21:16:01 INFO - Options: 21:16:01 INFO - -h, --help show this help message and exit 21:16:01 INFO - -d DEST, --destination=DEST 21:16:01 INFO - Directory to install application into. [default: 21:16:01 INFO - "/builds/slave/test"] 21:16:01 INFO - --app=APP Application being installed. [default: firefox] 21:16:01 INFO - mkdir: /builds/slave/test/build/application 21:16:01 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'] 21:16:01 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 21:16:17 INFO - Reading from file tmpfile_stdout 21:16:17 INFO - Output received: 21:16:17 INFO - /builds/slave/test/build/application/firefox/firefox 21:16:17 INFO - Running post-action listener: _resource_record_post_action 21:16:17 INFO - [mozharness: 2016-09-22 04:16:17.187309Z] Finished install step (success) 21:16:17 INFO - [mozharness: 2016-09-22 04:16:17.187556Z] Running run-tests step. 21:16:17 INFO - Running pre-action listener: _resource_record_pre_action 21:16:17 INFO - Running main action method: run_tests 21:16:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:16:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:16:17 INFO - Python 2.7.3 21:16:17 INFO - Return code: 0 21:16:17 INFO - grabbing minidump binary from tooltool 21:16:17 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')]} 21:16:17 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 0x14d2f60>, '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 0x14d3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d41d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:16:17 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 21:16:17 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 21:16:17 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 21:16:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:16:17 INFO - Return code: 0 21:16:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:16:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:16:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:16:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:16:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:16:17 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/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 21:16:17 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/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 21:16:17 INFO - Using env: {'DISPLAY': ':0', 21:16:17 INFO - 'HOME': '/home/cltbld', 21:16:17 INFO - 'LANG': 'en_US.UTF-8', 21:16:17 INFO - 'LANGUAGE': 'en_US:en', 21:16:17 INFO - 'LOGNAME': 'cltbld', 21:16:17 INFO - 'MAIL': '/var/mail/cltbld', 21:16:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:16:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:17 INFO - 'MOZ_NO_REMOTE': '1', 21:16:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:16:17 INFO - 'NO_EM_RESTART': '1', 21:16:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:17 INFO - 'PWD': '/builds/slave/test', 21:16:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:16:17 INFO - 'SHELL': '/bin/bash', 21:16:17 INFO - 'SHLVL': '1', 21:16:17 INFO - 'TERM': 'linux', 21:16:17 INFO - 'TMOUT': '86400', 21:16:17 INFO - 'USER': 'cltbld', 21:16:17 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429', 21:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:17 INFO - '_': '/tools/buildbot/bin/python'} 21:16:17 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/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 21:16:17 INFO - mozversion application_buildid: 20160921181859 21:16:17 INFO - mozversion application_changeset: f93ac29ad106222f2cd7e55a1d24795f89425dd4 21:16:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:16:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:16:17 INFO - mozversion application_name: Firefox 21:16:17 INFO - mozversion application_remotingname: firefox-dev 21:16:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:16:17 INFO - mozversion application_vendor: Mozilla 21:16:17 INFO - mozversion application_version: 51.0a2 21:16:17 INFO - mozversion platform_buildid: 20160921181859 21:16:17 INFO - mozversion platform_changeset: f93ac29ad106222f2cd7e55a1d24795f89425dd4 21:16:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:16:17 INFO - mozversion platform_version: 51.0a2 21:16:17 INFO - using testdate: 1474517777 21:16:17 INFO - actual date: 1474517777 21:16:17 INFO - starting webserver on 'localhost:35847' 21:16:17 INFO - SUITE-START | Running 2 tests 21:16:17 INFO - TEST-START | dromaeo_css 21:16:17 INFO - operating with platform_type : linux_ 21:16:17 INFO - Initialising browser for dromaeo_css test... 21:16:17 INFO - TEST-INFO | started process 25221 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsgg9DU/profile http://localhost:35847/getInfo.html) 21:16:21 INFO - PROCESS | 25221 | __metrics Screen width/height:1600/1200 21:16:21 INFO - PROCESS | 25221 | colorDepth:24 21:16:21 INFO - PROCESS | 25221 | Browser inner width/height: 1024/697 21:16:21 INFO - PROCESS | 25221 | __metrics 21:16:21 INFO - PROCESS | 25221 | 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" 21:16:21 INFO - PROCESS | 25221 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:16:22 INFO - PROCESS | 25221 | console.error: PushService: 21:16:22 INFO - PROCESS | 25221 | stateChangeProcessEnqueue: Error transitioning state 21:16:22 INFO - PROCESS | 25221 | UnknownError 21:16:22 INFO - TEST-INFO | 25221: exit 0 21:16:22 INFO - Browser initialized. 21:16:22 INFO - Running cycle 1/1 for dromaeo_css test... 21:16:22 INFO - TEST-INFO | started process 25315 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsgg9DU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:16:34 INFO - PROCESS | 25315 | RSS: Main: 144420864 21:16:34 INFO - PROCESS | 25315 | 21:16:51 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00044 seconds. 21:17:10 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00057 seconds. 21:17:22 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.05 seconds. 21:17:25 INFO - PROCESS | 25315 | 1474517845907 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] 21:17:31 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.03321 seconds. 21:17:46 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 21:17:52 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.06608 seconds. 21:18:01 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01656 seconds. 21:18:04 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.06658 seconds. 21:18:15 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 21:18:27 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01653 seconds. 21:18:28 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01621 seconds. 21:18:30 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01657 seconds. 21:18:34 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 21:18:36 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01645 seconds. 21:18:54 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.0006 seconds. 21:19:10 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00015 seconds. 21:19:19 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.03363 seconds. 21:19:35 INFO - PROCESS | 25315 | Cycle 1(1): loaded http://localhost:35847/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35847/tests/dromaeo/cssquery-ext.html) 21:19:36 INFO - PROCESS | 25315 | RSS: Main: 244871168 21:19:36 INFO - PROCESS | 25315 | 21:19:39 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01627 seconds. 21:19:52 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 21:19:54 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.0004 seconds. 21:20:23 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00023 seconds. 21:20:25 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00049 seconds. 21:20:33 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01603 seconds. 21:20:43 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00033 seconds. 21:20:48 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.0166 seconds. 21:20:53 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 21:21:56 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01622 seconds. 21:22:05 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.0002 seconds. 21:22:26 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00027 seconds. 21:22:36 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.0006 seconds. 21:22:38 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 21:22:38 INFO - PROCESS | 25315 | Cycle 1(1): loaded http://localhost:35847/tests/dromaeo/cssquery-ext.html (next: http://localhost:35847/tests/dromaeo/cssquery-jquery.html) 21:22:39 INFO - PROCESS | 25315 | RSS: Main: 238469120 21:22:39 INFO - PROCESS | 25315 | 21:22:42 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 21:22:51 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 21:22:59 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 21:23:31 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00023 seconds. 21:23:44 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00035 seconds. 21:23:48 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 21:24:10 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00035 seconds. 21:24:22 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00052 seconds. 21:24:29 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.0002 seconds. 21:25:04 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00056 seconds. 21:25:28 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 21:25:36 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00056 seconds. 21:25:40 INFO - PROCESS | 25315 | Cycle 1(1): loaded http://localhost:35847/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35847/tests/dromaeo/cssquery-mootools.html) 21:25:41 INFO - PROCESS | 25315 | RSS: Main: 174698496 21:25:41 INFO - PROCESS | 25315 | 21:25:44 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01741 seconds. 21:25:46 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01716 seconds. 21:25:50 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00031 seconds. 21:25:59 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.0002 seconds. 21:26:10 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 21:26:20 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00042 seconds. 21:26:48 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00049 seconds. 21:26:56 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00047 seconds. 21:27:18 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 21:27:20 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.0005 seconds. 21:27:32 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 21:27:45 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00052 seconds. 21:27:57 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00028 seconds. 21:28:05 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00021 seconds. 21:28:31 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 21:28:43 INFO - PROCESS | 25315 | Cycle 1(1): loaded http://localhost:35847/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35847/tests/dromaeo/cssquery-prototype.html) 21:28:44 INFO - PROCESS | 25315 | RSS: Main: 175685632 21:28:44 INFO - PROCESS | 25315 | 21:28:47 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.03295 seconds. 21:28:53 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 21:28:58 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00021 seconds. 21:29:04 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01597 seconds. 21:29:19 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01661 seconds. 21:29:47 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00034 seconds. 21:29:59 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00036 seconds. 21:30:08 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00036 seconds. 21:30:15 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00043 seconds. 21:30:31 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01655 seconds. 21:30:38 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00033 seconds. 21:30:42 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 21:30:44 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 21:30:54 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 21:30:56 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.0002 seconds. 21:30:58 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01618 seconds. 21:31:01 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01695 seconds. 21:31:24 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00054 seconds. 21:31:28 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00066 seconds. 21:31:32 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 21:31:44 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 21:31:46 INFO - PROCESS | 25315 | Cycle 1(1): loaded http://localhost:35847/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35847/tests/dromaeo/cssquery-yui.html) 21:31:47 INFO - PROCESS | 25315 | RSS: Main: 177250304 21:31:47 INFO - PROCESS | 25315 | 21:31:50 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:31:54 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00021 seconds. 21:32:09 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 21:32:24 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00064 seconds. 21:32:41 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.03356 seconds. 21:32:48 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00076 seconds. 21:32:56 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 21:33:12 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 21:33:24 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 21:33:39 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01615 seconds. 21:33:49 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.01641 seconds. 21:34:17 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 21:34:48 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00034 seconds. 21:34:49 INFO - PROCESS | 25315 | [GFX1-]: Refresh driver waiting for the compositor for 1.00015 seconds. 21:34:49 INFO - PROCESS | 25315 | Cycle 1(1): loaded http://localhost:35847/tests/dromaeo/cssquery-yui.html (next: http://localhost:35847/tests/dromaeo/cssquery-dojo.html) 21:34:50 INFO - PROCESS | 25315 | RSS: Main: 258584576 21:34:50 INFO - PROCESS | 25315 | 21:34:50 INFO - PROCESS | 25315 | __start_tp_report 21:34:50 INFO - PROCESS | 25315 | _x_x_mozilla_page_load 21:34:50 INFO - PROCESS | 25315 | _x_x_mozilla_page_load_details 21:34:50 INFO - PROCESS | 25315 | |i|pagename|runs| 21:34:50 INFO - PROCESS | 25315 | |0;dojo.html;3083.0442562805533;3103.2397062719638;3124.593802805635;3142.3558170575034;3160.4943209086537;5526.115821468561;5595.972020139904;5602.299712535933;5607.719614019303;5632.07070833313;5612.719364031792;5671.404502527237;5672.092465205568;5706.429357064291;5714.542836573075;5126.897462050757;5147.073526765181;5176.482351764831;5186.5850731941355;5229.398619158788;4998.5001499849905;5016.949830501703;5187.19598462239;5207.661502002376;5211.765470553815;5046.495350464961;5076.543111119999;5125.743712814363;5128.230765385185;5133.614978876599;5235.083860324447;5235.55497782689;5237.607179461536;5237.659552129099;5270.841874743764;4845.951540484621;5119.513646203604;5139.357580302462;5184.922226166611;5202.167653175474;5391.32608423947;5488.423715509894;5491.121420572689;5530.751116199781;5544.251526043973;5967.61210521319;5983.820485385445;6094.116353128771;6099.542534309944;6103.115048318014;5859.091840764689;5908.822735317947;6081.057436097412;6097.146399504073;6100.115483254903;5544.112941929272;5583.05088135018;5591.4688104629995;5616.719164041781;5638.323401191883;6166.907496387557;6175.3515880832765;6176.938230617726;6177.104319873683;6180.072989051589;5679.517241034476;5731.226284451629;5911.113333000085;5915.526757859361;5918.911216331759;5863.501602363847;5887.617304875255;5892.175095486636;5954.285485741739;5972.193753843262;5910.556708246899;5910.940890591125;5914.9704251479325;5932.2584676915385;5945.048792193228;5828.6502809831545;5868.941310586925;5926.703664816742;5945.375735547792;5964;4564.748938808331;4583.885402864888;4622.399088118523;4626.629869610423;4688.906221875612;4084.0810817566403;4104.630583247463;4107.363358679409;4111.8972025699595;4136.9793151034655;5776.96014717355;5779.826605201851;5782.161586569924;5787.681677507778;5820.505257053198;5842.883142337129;5883.794067207627;5887.175795388649;5910.970445147746;5976.043832986701;5555.638883472561;5557.666540007532;5606.775728970795;5696.316442026983;5730.312362516524;5201.427842937273;5210.270562121306;5211.557017653542;5211.869703257449;5212.4266330703595;6003.6397816131175;6150.723217455135;6178.567500274938;6181.629102253879;6182.412670796267;5813.157092221689;5948.375420580776;5992.131140984446;6012.7294271757;6110.541709371726;6248.781292654735;6294.118823364641;6294.15028971083;6299.6535190564955;6304.968475157595;5729.312482502127;5829.650220986754;5889.558283128868;5894.70526473666;5927.792527261525;5885.264341957255;5980.102984552352;6000.69996500182;6049.485793707408;6119.173911521888;5956;5958.493528050164;5961.433663801932;5965.8806823862415;5968.313643931004;5901.616394934315;5943.613665111754;5950.19672344228;5951.196588460502;5959.702014899324;5682.602217844877;5684.545236381162;5858.297004359334;5879.029960056543;5903;6028.4272994065495;6029.487493563096;6031.638101713911;6037.75848966054;6059.545534084873;5589.720513974204;5829.446202610915;5880.617759845596;5881;5884.852878678067 21:34:50 INFO - PROCESS | 25315 | |1;ext.html;598.7694737526051;621.2234706616729;656.3731636287345;660.6531570925266;672.9360710732478;34841.3031739365;47093.76453117734;48206.27690584635;48262.276065859;51108.48891511084;48776.75611621946;49148.50851491484;49224;49263.75368123163;49299.50700493003;8574.614142363609;9185.586648600813;9339;9344.766380840465;9347.906520934806;8476.66093356265;10866.836997445045;11126.054285385751;11144.60993865211;11501.884981150208;11185.217034807527;11230.887691123065;11256.887431125666;11259.099272058256;11278.323300601949;11028.22802403832;11074;11081.833772493419;11091.057260132899;11092.33445993243;10946.616868409646;11005.889941100566;11015.724606884813;11022.118230541537;11025.393603351813;46257.76871115657;46375.53624463746;46429.53570464286;46582.301265481045;46622.53377466216;3778.149916268845;3964.345882929313;4000.3599424092004;4250.723702959329;4634.39752832133;34219.144521386916;34328.48507272393;34581.481277780855;34626.6537334626;36135.819320903225;4701.2242979908615;4720.763961801897;4767.570918617306;4822.493638167977;4871.732054736988;365155;365419;371376;371636;372725;64527.35472645213;64753.35246647568;64919.67540162269;64931;64992.35007649958;40275;41835.372469412985;42053.579464204966;44330;44330.55669443264;18035.72946405805;18077.45767626947;18122.365717199835;18170.364037258634;18173.182206800724;12133.21134126279;12646.114771965875;13307.068505204736;13340.733185336432;14030.368633411521;20195;20810.37568872906;20975.05612247422;21002.474938126365;21036.368908932756;7422.443316751265;7441.144268409094;7492.288232617892;7635.389168866588;7640.694372225049;962.7978124593914;1056.4664844253757;1063.0645032371617;1093.9562417503212;1099.8570185875785;33857.32285354279;35228.94867090318;35611.82194089013;35646.64353356433;36157.45763813545;1055.1400608504046;1094.8303013032992;1119.9920071935323;1148.4487446025955;1179.5753528729651;17521.755165917293;18163.636727265646;18447.446576602724;18554.44336669902;18575.90712046431;644354;649495;652274;652981;653123;64394.67802660957;65153.67423162854;65153.67423162854;65161.67419162969;65168;1306.9477220911058;1343.623785340087;1368.097055943053;1436.102435977514;1452.651363672732;32533.511997319587;36728.449073263386;36794.816025919696;36814;36839;54363;60969;62920;62931.370686292554;67659;570015;570976;573508;574742;575400;30772.230694232825;32734.181645459055;32749;49089.754551227015;49430.505694944026;52344;53123.46876531185;53506.46493535171;53622.73188634032;54815.72592137014;43623;43721;43994.78002609967;45079;45391.773041134584;44839.55160448354;45453.318200226364;45470.54529454663;45970.16119355188;46387.76806115948;44546.55453445424;44852.55147448484;45533.54466455293;45861.08277834491;45981.540184599064;37509.4373584391;39255.214895701356;39437.6056239434;39860.40209396919;40222.79888600538;28959.13122606325;29349.26626834346;29396.412071758874;29611.851940740158;29626.555601666423 21:34:50 INFO - PROCESS | 25315 | |2;jquery.html;400.9258287216867;433.0969927700744;438.2899702481979;438.93980678721834;452.88225061484025;18025.909870450647;20822.167113315467;20846.270380536702;21085.051172697225;21754.238601648947;21059;21195.788042119613;21204;21248.57502849946;21386.46533836655;4793.952060479394;5990.6106103103375;6043.1841701370195;6050.788222412217;6060.3939606039485;5870.11948207768;6025.517958563304;6039.184710064129;6108.267007959051;6118.0822876568645;6014.188084608566;6021.397860213988;6026.8794624107495;6032.125341825433;6035.064564992434;5810.506106980891;6013.1581578579035;6023.488003519706;6029.849253768647;6052.727627256784;5472.699001554913;5479.863003424906;5492.340919089695;5492.5605951524185;5497.038018346813;25005.749942500523;25369.873150634314;25382.619260711104;25431.237062888144;25463.61804572933;2482.416632091475;2492.277239600514;2558.872056397173;2570.6144078388206;2650.3374156460886;15223.771643425358;15409.383624655;15687.529374118794;15741.370345186178;16028.839711602852;1990.7113468547125;2062.6596611559075;2093.3824521765964;2117.184883819725;2176.173054239379;63218.683906580634;65689.67155164242;81916.59041704812;83454;85443.57278213631;11530.192886498033;11578.305301681927;11773.411329433495;11837.289762614271;12040.458179381949;17030.31878724862;17046.573835654206;17051.658966820592;17062.146892655317;17070;8870.15733505316;8922.86615700765;8945.37382383227;9064.138906803842;9109.18017378439;8679.609417576225;8709.303255739525;8774.473531588124;8911.777205569782;8949.239314658196;7071.575705457586;7178.712851485987;7292.645383123717;7301.525400848928;7317.926820731831;1844.7087039072703;1967.124629539841;2011.8289945354516;2107.3467225159943;2133.2213741984065;1052.8473371361263;1074.5346258316865;1076.2143635146388;1111.555377848867;1116.9273997190319;21130.78869211319;21344.679829802564;21350.679739803916;21474.24840130588;21499.89250053771;1076.1337123615408;1109.9223054386116;1112.9593829769192;1117.620009196877;1147.5467190459722;11733;12264.570740024057;12435.378231088409;12442.253464792142;12509;486159;490632;492905;505471;548484;63381.68309158517;92388.53805730928;112187.07345242877;113523;114021.42989285167;1046.3094357723867;1056.8203405420943;1080.9027187553158;1097.1223021582703;1101.4107452513028;19099.90450047741;19330.71003934914;19349.806501935363;19513.317033903746;19531.902340488206;32055.358892822478;39435;40105.79947100362;41262.79368603138;48391.75804120957;65417.672911635134;67730.3226967724;68344;68432.3156768426;69537.30462695511;9444.622215111318;9446.763830904007;9511.524423778921;9551.283653725859;9661.951690241503;11414.429278536207;14063.367148478139;14253.650392158042;14470.927645361706;14700.970932035083;11900.107491938312;12416.255024698547;12513.874861251636;12689.36553172356;12808.423620937267;5962.731677074456;11089.39008354516;11617.012553933011;12254.080943929062;12622.936885315514;12191.634250972485;12225.083118766306;13033.47866085384;13101.082924195214;13324.533641322507;8963.507007114671;9074.364794464323;9138.543072846198;9150.039245879354;9330.580123894306;9597.856032159663;12554.372281386077;12969.54606588765;13015.414306356426;13110.737785244051 21:34:50 INFO - PROCESS | 25315 | |3;mootools.html;1541.529833400813;1578.0768250573415;1760.3838656470236;1860.051373799362;1867.4397680695788;9130.178283954443;9825.705228843146;9841.901580984188;9865.99999999999;9872.506374681265;8231.053428855683;8505.106963768809;9131.726048218565;9467.95266023669;9656.082672146134;2482.5531404347257;2505.0480817289404;2505.1732928133674;2514.2080244722947;2518.5970244760842;2473.245660073677;2555.7572030657057;2556.1309154887335;2559.846409215453;2560.7183209846994;2522.823402361835;2528.8229823912334;2545.643609894616;2549.9490010199784;2560.398306397995;2478.7769100781015;2486.490269494749;2495.7878580320694;2500.199936020474;2517.332906779705;2474.542209691213;2478.9752102478924;2484.6769919910475;2491.28998235502;2494;9586.952065239699;9771.902280977241;9782.853257201148;9786;9797.216222702167;5648.350439699405;5803.21656576361;5819.272590926134;5824.242848429677;5825.388334224887;3609.3322735294055;3692.5568931728367;3752.5684546277253;3789.9621003789885;3808.828602712857;1226.4235809169677;1275.7320962597867;1283.7149178412471;1300.5513097981182;1303.8630943750863;13365.532206372827;13385.129966552144;13457.596272111852;13468.730625387536;13522.12106213093;4938.753062346868;4974.129527332775;5083.822066227664;5110.948890511122;5112.309838171799;11366.772664546663;11369.31784092957;11376.715582110348;11378.31730096197;11392.658220253239;5865.296164460292;5867.119932010232;5874.441928016831;5875.030619947746;5882.176495290662;4736.597389221886;4794.041191761592;5133.948660513422;5236.266922630834;5388.568914486832;4314.525402205755;4380.3210502372185;4389.868303950887;4492.056668099735;4523.570260825217;1413.2792275939346;1430.7853821926585;1444.1407362619225;1457.3806132393902;1463.1367493179075;1040.7502199472071;1049.2078480746986;1050.5062620568322;1051.4847724614883;1065.8294672852307;6420.775272865428;6427.132337134521;6441.774537891245;6630.204375474877;6750.459963202932;485.8049198970572;492.35009787081424;504.7274471785268;517.9352580927384;518.8495336352491;4430.556944305608;4474.172278128492;4483.237849565582;4534.229181039232;4561.5666511682;11656.475458604213;12143.149979501526;12156.452959616661;12165.270083795001;12202.938985305016;11239.157063220127;11804.232724872856;11811.468483918245;12134.453949572291;12156.574519891938;8783.209511144029;8933.776655583662;8941.239994600532;8949.373543851867;8986;6446.9355306448215;6621.33786621334;6655.467562595078;6690.330966903271;6740.966295168493;10885.727856803327;11144.55421783152;11147.052500537546;11257.324560526067;11436.599719009795;10699.14406847466;11134.220604557604;11408.600698975495;11499.655010349703;11518.827217591575;7535.660895259836;7802.453828232196;8071.43499955021;8448.788780280542;8676.08901065379;9190.080991900757;9474.289428292768;9518.809623807347;9524.14282714559;9531.57107930131;8059.1134975153855;8262.669493220204;8305.169483051646;8312.127226641116;8633.481991080555;8030.23712747288;8038.196180382149;8243.793905152419;8338.416310858182;8445.366597505335;7702.730404435818;7776.7278145264645;7786.688532458638;7799.18108598589;7837.843243135219;5615.326160860723;5673.602847800779;5777.364489906191;5791.986402379483;6076.1493390924425;5634;5811.82564523065;5891.94108058931;6002.969985150046;6060.909086363797 21:34:50 INFO - PROCESS | 25315 | |4;prototype.html;352.10565164482216;359.17569178734806;359.9064243296742;365.8335457366898;366.5875889624171;5586.41342659021;5597.3283206015285;5607.6074674772735;5653.575981801366;5690.2887139107615;5420.268263784388;5571.387147413781;5610.887782244363;5626.368353177584;5626.578006649497;4359.950823113148;4361.579323475462;4367.825286988516;4377.78111094444;4384.027187765502;4400.97799511002;4403.427554417937;4415.8233670653135;4425.601695847373;4448.755318457484;4256.851010214643;4382.408374869384;4398.780060996937;4398.9560104399025;4401.317795741666;4305.062721775198;4348.695591308609;4349.673774466928;4358.2808836541935;4395.7362558246605;3899.005753532859;3915.960840391617;3921.960680419691;3933.1347103637154;3939.704522160821;5479.945200547983;5489.58828087895;5491.643043202179;5494.835154945358;5523.7238138092935;4883.243097319921;4966.47851975541;5000.799968001275;5042.873928151789;5074.695518268915;5811.593188476766;5820.330661973842;5822.097574875901;5847.275053859122;5854.18041474194;4317.870463886088;4319.984803571189;4322.719023263509;4328.9783551082355;4343.740315308557;5792.218050563162;5795.391483894173;6090.817275481742;6096.116063170815;6102.237220347412;4153.335466325373;4200.6219440250525;4275.8075886585175;4288.656907447396;4315.935260971088;4345.130973805251;4346.717463364871;4358.825646974149;4378.496472905656;4385.846495372647;4683.601893839062;4806.404541547595;4831.637627177975;4993.90012199754;5003.4746351633285;5747.48272655463;5816.738246778905;5839.737211825393;5843.620164689281;5850.824475265749;1538.7768773528;1558.6960542694292;1560.2978659603225;1563.1558958162466;1564.1631727026213;970.4274478057838;1017.6133069433567;1019.1846522781744;1023.8668973033607;1030.7629245273629;5548.500634942875;5562.249096371938;5579.944200558024;5602.411746766612;5613.445882123624;5931.73307201177;5940.346561878277;5957.6127551709;5976.850578735566;5988.191594134736;5441.483059109379;5676.801311954062;5743.655380677173;5752.194692743019;5754.1944127822135;3169.7464202863716;3180.9840950795096;3186.6654001329525;3190.5961376994046;3192.696693814084;6085.208922840003;6148.231471066117;6212.596181248205;6253.812385628438;6254.8123556293385;6056;6103.542234332443;6113.23584551931;6159.291681456601;6162.2437605282275;6137.693115344215;6153.676959453739;6209.875802484015;6225.62646241227;6247.656378899204;5768;6027.578069535266;6037.969810150921;6052;6056.091586262096;5969.8955693196895;5997.91003134962;6007.699615019144;6037.215162028908;6042.607230530001;5926.199963005111;5981.371955944737;6135.570510064431;6154.969225153845;6206;4334.718243314174;4353.128154893299;4376.431063961664;4408.691391602557;4411.933820992624;5894.233749612523;6087.452129308206;6162.366972752135;6174.25908890936;6178.320384757583;5843.532517398513;5927.614705044331;5932.2584676915385;5932.970335148297;5956.791512297049;5920.437558431942;5933.910991335047;5955.642661440327;5989.191459153133;6031.276246850406;5717.199592057199;5888.793892213752;5913.733881975236;5925.911111333419;5929.584929054925;5952.613080149777;5956.642601443928;5988.8802223956145;5990.81335312599;6069;5686.914696279477;5760.452756988079;5827.854303642443;5830.192640281526;5908.793192238251 21:34:50 INFO - PROCESS | 25315 | |5;yui.html;813.820959388935;827.346396346886;869.1395518436749;872.0712441250072;911.6490151291753;15821.288042038108;16577.585560360996;16840.410585629474;17001.65996680069;17033.148342582866;15104.244787760596;15244.84755152448;15346.155961422117;16218.918905405457;16824.83175168248;718.2243177368442;735.6836560279078;861.7285555050171;878.7934835313728;891.1355984694835;869.1873098652779;873.52829472085;874.7506960516222;875.7679215008028;876.6142897125244;764.7896828372214;927.188709878856;934.1359242700474;936.0826390137669;936.3913456253422;780.4185881518276;893.1247377569986;920.3189639666665;924.5746956400064;927.5455027036771;676.3202981004108;676.3270545806904;676.7597502886454;676.7766637009775;676.939075483204;16028.519144425685;16148.515544533566;16224.594385140348;16242;16246.837531624651;15296;15609.45366912164;15818.050916944905;15865.20673966297;16122.516324510283;14349.282535873164;14388;14730.410783568645;14771.409143634242;15391.230438478031;4838.903221935541;4842.66101372904;4896.167651499253;4938.851834444972;4992.251162325644;81386.18613813905;81403.59298203471;81716.59141704314;82462;86412.13587864104;12894.548690795777;13025.348732563334;13046.739065218642;13182.802257966137;13447.125936814076;22837.429064273194;23737.643935340984;23787.762122378554;25055.749442505705;25100.874495627406;9501.524923753923;9587.472689002172;9922.702318930444;9951.104400603981;10148.543315550818;5077.441481437039;5272.736363181903;5312.362516498045;5462.562994960394;5551.916721249185;4129.876103716893;4217.831286748496;4251.043515209115;4260.808263628144;4264.424302719155;742.8959945607619;748.4947660329256;754.3135746470795;757.5606148433895;769.6036541181296;760.627292626609;776.6621519638975;792.6433105102727;801.8516574433631;803.863343231652;10277.280590358752;10606.628767993237;10622.150227981743;10667.83998240027;10703.357798531957;655.7606473637088;771.3906014248793;773.8817408844278;784.6508303804866;826.09955148888;5135.12702840518;5378.300820893259;5387.8114266001285;5399.78400863961;5404.216388623628;85486.57256713677;102563.48718256361;124481;132732.3363383177;133433;101625;102807.4859625712;103984.48007759913;107569;110217;93037;93660.59509107369;94044.52977735204;95380.52309738407;97978.51010744901;10796.730081748019;10891.0742586881;10919.836202457129;10946.698646157392;11147.163962702667;53854.461455384946;54277.72861135827;54482.72758636181;55367.16949245845;55491.72254138865;77957.220427795;81408;83539;84431.57784211039;86735;12953.481860725466;14059.718805624036;14162.929185354007;14318.14091154534;14646.048006879519;15257.923710381378;15487.148206848731;15875.682486350439;15974.68050639004;16195.595110122342;14988.2505874708;15149.242537872842;15382.461613843461;15429.459968901483;15809.13049782273;14708.057271892943;15073.924630376778;15142.167180805161;15263.618409539851;15355.923220384273;14909.3290801912;14914.105153690813;15150.090994540364;15303.464378747136;15369.769453458432;12093.75812483763;12729.172603780724;12844.678883027998;12878.356082195665;12908.290044047668;11914.940425297818;12281.570145044881;12473.438695258554;12699.682507937006;13133.408996594984 21:34:50 INFO - PROCESS | 25315 | __end_tp_report 21:34:50 INFO - PROCESS | 25315 | __start_cc_report 21:34:50 INFO - PROCESS | 25315 | _x_x_mozilla_cycle_collect,912 21:34:50 INFO - PROCESS | 25315 | __end_cc_report 21:34:50 INFO - PROCESS | 25315 | __startTimestamp1474518890248__endTimestamp 21:34:50 INFO - PROCESS | 25315 | 21:34:50 INFO - PROCESS | 25315 | ------- Summary: start ------- 21:34:50 INFO - PROCESS | 25315 | Number of tests: 6 21:34:50 INFO - PROCESS | 25315 | 21:34:50 INFO - PROCESS | 25315 | [#0] dojo.html Cycles:165 Average:5578.78 Median:5828.65 stddev:641.03 (11.0%) stddev-sans-first:612.37 21:34:50 INFO - PROCESS | 25315 | Values: 3083.0 3103.2 3124.6 3142.4 3160.5 5526.1 5596.0 5602.3 5607.7 5632.1 5612.7 5671.4 5672.1 5706.4 5714.5 5126.9 5147.1 5176.5 5186.6 5229.4 4998.5 5016.9 5187.2 5207.7 5211.8 5046.5 5076.5 5125.7 5128.2 5133.6 5235.1 5235.6 5237.6 5237.7 5270.8 4846.0 5119.5 5139.4 5184.9 5202.2 5391.3 5488.4 5491.1 5530.8 5544.3 5967.6 5983.8 6094.1 6099.5 6103.1 5859.1 5908.8 6081.1 6097.1 6100.1 5544.1 5583.1 5591.5 5616.7 5638.3 6166.9 6175.4 6176.9 6177.1 6180.1 5679.5 5731.2 5911.1 5915.5 5918.9 5863.5 5887.6 5892.2 5954.3 5972.2 5910.6 5910.9 5915.0 5932.3 5945.0 5828.7 5868.9 5926.7 5945.4 5964.0 4564.7 4583.9 4622.4 4626.6 4688.9 4084.1 4104.6 4107.4 4111.9 4137.0 5777.0 5779.8 5782.2 5787.7 5820.5 5842.9 5883.8 5887.2 5911.0 5976.0 5555.6 5557.7 5606.8 5696.3 5730.3 5201.4 5210.3 5211.6 5211.9 5212.4 6003.6 6150.7 6178.6 6181.6 6182.4 5813.2 5948.4 5992.1 6012.7 6110.5 6248.8 6294.1 6294.2 6299.7 6305.0 5729.3 5829.7 5889.6 5894.7 5927.8 5885.3 5980.1 6000.7 6049.5 6119.2 5956.0 5958.5 5961.4 5965.9 5968.3 5901.6 5943.6 5950.2 5951.2 5959.7 5682.6 5684.5 5858.3 5879.0 5903.0 6028.4 6029.5 6031.6 6037.8 6059.5 5589.7 5829.4 5880.6 5881.0 5884.9 21:34:50 INFO - PROCESS | 25315 | 21:34:50 INFO - PROCESS | 25315 | [#1] ext.html Cycles:180 Average:69853.76 Median:41055.19 stddev:144883.12 (352.9%) stddev-sans-first:145196.25 21:34:50 INFO - PROCESS | 25315 | Values: 598.8 621.2 656.4 660.7 672.9 34841.3 47093.8 48206.3 48262.3 51108.5 48776.8 49148.5 49224.0 49263.8 49299.5 8574.6 9185.6 9339.0 9344.8 9347.9 8476.7 10866.8 11126.1 11144.6 11501.9 11185.2 11230.9 11256.9 11259.1 11278.3 11028.2 11074.0 11081.8 11091.1 11092.3 10946.6 11005.9 11015.7 11022.1 11025.4 46257.8 46375.5 46429.5 46582.3 46622.5 3778.1 3964.3 4000.4 4250.7 4634.4 34219.1 34328.5 34581.5 34626.7 36135.8 4701.2 4720.8 4767.6 4822.5 4871.7 365155.0 365419.0 371376.0 371636.0 372725.0 64527.4 64753.4 64919.7 64931.0 64992.4 40275.0 41835.4 42053.6 44330.0 44330.6 18035.7 18077.5 18122.4 18170.4 18173.2 12133.2 12646.1 13307.1 13340.7 14030.4 20195.0 20810.4 20975.1 21002.5 21036.4 7422.4 7441.1 7492.3 7635.4 7640.7 962.8 1056.5 1063.1 1094.0 1099.9 33857.3 35228.9 35611.8 35646.6 36157.5 1055.1 1094.8 1120.0 1148.4 1179.6 17521.8 18163.6 18447.4 18554.4 18575.9 644354.0 649495.0 652274.0 652981.0 653123.0 64394.7 65153.7 65153.7 65161.7 65168.0 1306.9 1343.6 1368.1 1436.1 1452.7 32533.5 36728.4 36794.8 36814.0 36839.0 54363.0 60969.0 62920.0 62931.4 67659.0 570015.0 570976.0 573508.0 574742.0 575400.0 30772.2 32734.2 32749.0 49089.8 49430.5 52344.0 53123.5 53506.5 53622.7 54815.7 43623.0 43721.0 43994.8 45079.0 45391.8 44839.6 45453.3 45470.5 45970.2 46387.8 44546.6 44852.6 45533.5 45861.1 45981.5 37509.4 39255.2 39437.6 39860.4 40222.8 28959.1 29349.3 29396.4 29611.9 29626.6 21:34:50 INFO - PROCESS | 25315 | 21:34:50 INFO - PROCESS | 25315 | [#2] jquery.html Cycles:180 Average:30492.55 Median:2329.29 stddev:83339.59 (3577.9%) stddev-sans-first:83542.75 21:34:50 INFO - PROCESS | 25315 | Values: 400.9 433.1 438.3 438.9 452.9 18025.9 20822.2 20846.3 21085.1 21754.2 21059.0 21195.8 21204.0 21248.6 21386.5 4794.0 5990.6 6043.2 6050.8 6060.4 5870.1 6025.5 6039.2 6108.3 6118.1 6014.2 6021.4 6026.9 6032.1 6035.1 5810.5 6013.2 6023.5 6029.8 6052.7 5472.7 5479.9 5492.3 5492.6 5497.0 25005.7 25369.9 25382.6 25431.2 25463.6 2482.4 2492.3 2558.9 2570.6 2650.3 15223.8 15409.4 15687.5 15741.4 16028.8 1990.7 2062.7 2093.4 2117.2 2176.2 63218.7 65689.7 81916.6 83454.0 85443.6 11530.2 11578.3 11773.4 11837.3 12040.5 17030.3 17046.6 17051.7 17062.1 17070.0 8870.2 8922.9 8945.4 9064.1 9109.2 8679.6 8709.3 8774.5 8911.8 8949.2 7071.6 7178.7 7292.6 7301.5 7317.9 1844.7 1967.1 2011.8 2107.3 2133.2 1052.8 1074.5 1076.2 1111.6 1116.9 21130.8 21344.7 21350.7 21474.2 21499.9 1076.1 1109.9 1113.0 1117.6 1147.5 11733.0 12264.6 12435.4 12442.3 12509.0 486159.0 490632.0 492905.0 505471.0 548484.0 63381.7 92388.5 112187.1 113523.0 114021.4 1046.3 1056.8 1080.9 1097.1 1101.4 19099.9 19330.7 19349.8 19513.3 19531.9 32055.4 39435.0 40105.8 41262.8 48391.8 65417.7 67730.3 68344.0 68432.3 69537.3 9444.6 9446.8 9511.5 9551.3 9662.0 11414.4 14063.4 14253.7 14470.9 14701.0 11900.1 12416.3 12513.9 12689.4 12808.4 5962.7 11089.4 11617.0 12254.1 12622.9 12191.6 12225.1 13033.5 13101.1 13324.5 8963.5 9074.4 9138.5 9150.0 9330.6 9597.9 12554.4 12969.5 13015.4 13110.7 21:34:50 INFO - PROCESS | 25315 | 21:34:50 INFO - PROCESS | 25315 | [#3] mootools.html Cycles:180 Average:6338.30 Median:4765.32 stddev:3626.39 (76.1%) stddev-sans-first:3618.64 21:34:50 INFO - PROCESS | 25315 | Values: 1541.5 1578.1 1760.4 1860.1 1867.4 9130.2 9825.7 9841.9 9866.0 9872.5 8231.1 8505.1 9131.7 9468.0 9656.1 2482.6 2505.0 2505.2 2514.2 2518.6 2473.2 2555.8 2556.1 2559.8 2560.7 2522.8 2528.8 2545.6 2549.9 2560.4 2478.8 2486.5 2495.8 2500.2 2517.3 2474.5 2479.0 2484.7 2491.3 2494.0 9587.0 9771.9 9782.9 9786.0 9797.2 5648.4 5803.2 5819.3 5824.2 5825.4 3609.3 3692.6 3752.6 3790.0 3808.8 1226.4 1275.7 1283.7 1300.6 1303.9 13365.5 13385.1 13457.6 13468.7 13522.1 4938.8 4974.1 5083.8 5110.9 5112.3 11366.8 11369.3 11376.7 11378.3 11392.7 5865.3 5867.1 5874.4 5875.0 5882.2 4736.6 4794.0 5133.9 5236.3 5388.6 4314.5 4380.3 4389.9 4492.1 4523.6 1413.3 1430.8 1444.1 1457.4 1463.1 1040.8 1049.2 1050.5 1051.5 1065.8 6420.8 6427.1 6441.8 6630.2 6750.5 485.8 492.4 504.7 517.9 518.8 4430.6 4474.2 4483.2 4534.2 4561.6 11656.5 12143.1 12156.5 12165.3 12202.9 11239.2 11804.2 11811.5 12134.5 12156.6 8783.2 8933.8 8941.2 8949.4 8986.0 6446.9 6621.3 6655.5 6690.3 6741.0 10885.7 11144.6 11147.1 11257.3 11436.6 10699.1 11134.2 11408.6 11499.7 11518.8 7535.7 7802.5 8071.4 8448.8 8676.1 9190.1 9474.3 9518.8 9524.1 9531.6 8059.1 8262.7 8305.2 8312.1 8633.5 8030.2 8038.2 8243.8 8338.4 8445.4 7702.7 7776.7 7786.7 7799.2 7837.8 5615.3 5673.6 5777.4 5792.0 6076.1 5634.0 5811.8 5891.9 6003.0 6060.9 21:34:50 INFO - PROCESS | 25315 | 21:34:50 INFO - PROCESS | 25315 | [#4] prototype.html Cycles:180 Average:4958.00 Median:5619.91 stddev:1445.80 (25.7%) stddev-sans-first:1407.91 21:34:50 INFO - PROCESS | 25315 | Values: 352.1 359.2 359.9 365.8 366.6 5586.4 5597.3 5607.6 5653.6 5690.3 5420.3 5571.4 5610.9 5626.4 5626.6 4360.0 4361.6 4367.8 4377.8 4384.0 4401.0 4403.4 4415.8 4425.6 4448.8 4256.9 4382.4 4398.8 4399.0 4401.3 4305.1 4348.7 4349.7 4358.3 4395.7 3899.0 3916.0 3922.0 3933.1 3939.7 5479.9 5489.6 5491.6 5494.8 5523.7 4883.2 4966.5 5000.8 5042.9 5074.7 5811.6 5820.3 5822.1 5847.3 5854.2 4317.9 4320.0 4322.7 4329.0 4343.7 5792.2 5795.4 6090.8 6096.1 6102.2 4153.3 4200.6 4275.8 4288.7 4315.9 4345.1 4346.7 4358.8 4378.5 4385.8 4683.6 4806.4 4831.6 4993.9 5003.5 5747.5 5816.7 5839.7 5843.6 5850.8 1538.8 1558.7 1560.3 1563.2 1564.2 970.4 1017.6 1019.2 1023.9 1030.8 5548.5 5562.2 5579.9 5602.4 5613.4 5931.7 5940.3 5957.6 5976.9 5988.2 5441.5 5676.8 5743.7 5752.2 5754.2 3169.7 3181.0 3186.7 3190.6 3192.7 6085.2 6148.2 6212.6 6253.8 6254.8 6056.0 6103.5 6113.2 6159.3 6162.2 6137.7 6153.7 6209.9 6225.6 6247.7 5768.0 6027.6 6038.0 6052.0 6056.1 5969.9 5997.9 6007.7 6037.2 6042.6 5926.2 5981.4 6135.6 6155.0 6206.0 4334.7 4353.1 4376.4 4408.7 4411.9 5894.2 6087.5 6162.4 6174.3 6178.3 5843.5 5927.6 5932.3 5933.0 5956.8 5920.4 5933.9 5955.6 5989.2 6031.3 5717.2 5888.8 5913.7 5925.9 5929.6 5952.6 5956.6 5988.9 5990.8 6069.0 5686.9 5760.5 5827.9 5830.2 5908.8 21:34:50 INFO - PROCESS | 25315 | 21:34:50 INFO - PROCESS | 25315 | [#5] yui.html Cycles:180 Average:22535.71 Median:4173.85 stddev:31772.33 (761.2%) stddev-sans-first:31819.59 21:34:50 INFO - PROCESS | 25315 | Values: 813.8 827.3 869.1 872.1 911.6 15821.3 16577.6 16840.4 17001.7 17033.1 15104.2 15244.8 15346.2 16218.9 16824.8 718.2 735.7 861.7 878.8 891.1 869.2 873.5 874.8 875.8 876.6 764.8 927.2 934.1 936.1 936.4 780.4 893.1 920.3 924.6 927.5 676.3 676.3 676.8 676.8 676.9 16028.5 16148.5 16224.6 16242.0 16246.8 15296.0 15609.5 15818.1 15865.2 16122.5 14349.3 14388.0 14730.4 14771.4 15391.2 4838.9 4842.7 4896.2 4938.9 4992.3 81386.2 81403.6 81716.6 82462.0 86412.1 12894.5 13025.3 13046.7 13182.8 13447.1 22837.4 23737.6 23787.8 25055.7 25100.9 9501.5 9587.5 9922.7 9951.1 10148.5 5077.4 5272.7 5312.4 5462.6 5551.9 4129.9 4217.8 4251.0 4260.8 4264.4 742.9 748.5 754.3 757.6 769.6 760.6 776.7 792.6 801.9 803.9 10277.3 10606.6 10622.2 10667.8 10703.4 655.8 771.4 773.9 784.7 826.1 5135.1 5378.3 5387.8 5399.8 5404.2 85486.6 102563.5 124481.0 132732.3 133433.0 101625.0 102807.5 103984.5 107569.0 110217.0 93037.0 93660.6 94044.5 95380.5 97978.5 10796.7 10891.1 10919.8 10946.7 11147.2 53854.5 54277.7 54482.7 55367.2 55491.7 77957.2 81408.0 83539.0 84431.6 86735.0 12953.5 14059.7 14162.9 14318.1 14646.0 15257.9 15487.1 15875.7 15974.7 16195.6 14988.3 15149.2 15382.5 15429.5 15809.1 14708.1 15073.9 15142.2 15263.6 15355.9 14909.3 14914.1 15150.1 15303.5 15369.8 12093.8 12729.2 12844.7 12878.4 12908.3 11914.9 12281.6 12473.4 12699.7 13133.4 21:34:50 INFO - PROCESS | 25315 | -------- Summary: end -------- 21:34:50 INFO - PROCESS | 25315 | 21:34:50 INFO - TEST-INFO | 25315: exit 0 21:34:50 INFO - TEST-OK | dromaeo_css | took 1113450ms 21:34:50 INFO - TEST-START | kraken 21:34:50 INFO - operating with platform_type : linux_ 21:34:50 INFO - Initialising browser for kraken test... 21:34:50 INFO - TEST-INFO | started process 25635 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDdAzWX/profile http://localhost:35847/getInfo.html) 21:34:54 INFO - PROCESS | 25635 | __metrics Screen width/height:1600/1200 21:34:54 INFO - PROCESS | 25635 | colorDepth:24 21:34:54 INFO - PROCESS | 25635 | Browser inner width/height: 1024/697 21:34:54 INFO - PROCESS | 25635 | __metrics 21:34:55 INFO - PROCESS | 25635 | 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" 21:34:55 INFO - PROCESS | 25635 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:34:55 INFO - PROCESS | 25635 | console.error: PushService: 21:34:55 INFO - PROCESS | 25635 | stateChangeProcessEnqueue: Error transitioning state 21:34:55 INFO - PROCESS | 25635 | UnknownError 21:34:56 INFO - TEST-INFO | 25635: exit 0 21:34:56 INFO - Browser initialized. 21:34:56 INFO - Running cycle 1/1 for kraken test... 21:34:56 INFO - TEST-INFO | started process 25724 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDdAzWX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:35:08 INFO - PROCESS | 25724 | RSS: Main: 143958016 21:35:08 INFO - PROCESS | 25724 | 21:35:10 INFO - PROCESS | 25724 | Cycle 1(1): loaded http://localhost:35847/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35847/tests/kraken/driver.html?testName=audio-beat-detection) 21:35:11 INFO - PROCESS | 25724 | RSS: Main: 185692160 21:35:11 INFO - PROCESS | 25724 | 21:35:14 INFO - PROCESS | 25724 | Cycle 1(1): loaded http://localhost:35847/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35847/tests/kraken/driver.html?testName=audio-dft) 21:35:14 INFO - PROCESS | 25724 | RSS: Main: 174952448 21:35:14 INFO - PROCESS | 25724 | 21:35:17 INFO - PROCESS | 25724 | Cycle 1(1): loaded http://localhost:35847/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35847/tests/kraken/driver.html?testName=audio-fft) 21:35:18 INFO - PROCESS | 25724 | RSS: Main: 265781248 21:35:18 INFO - PROCESS | 25724 | 21:35:20 INFO - PROCESS | 25724 | Cycle 1(1): loaded http://localhost:35847/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35847/tests/kraken/driver.html?testName=audio-oscillator) 21:35:20 INFO - PROCESS | 25724 | RSS: Main: 172929024 21:35:20 INFO - PROCESS | 25724 | 21:35:22 INFO - PROCESS | 25724 | Cycle 1(1): loaded http://localhost:35847/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35847/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:35:23 INFO - PROCESS | 25724 | RSS: Main: 173764608 21:35:23 INFO - PROCESS | 25724 | 21:35:26 INFO - PROCESS | 25724 | Cycle 1(1): loaded http://localhost:35847/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35847/tests/kraken/driver.html?testName=imaging-darkroom) 21:35:27 INFO - PROCESS | 25724 | RSS: Main: 210190336 21:35:27 INFO - PROCESS | 25724 | 21:35:30 INFO - PROCESS | 25724 | Cycle 1(1): loaded http://localhost:35847/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35847/tests/kraken/driver.html?testName=imaging-desaturate) 21:35:30 INFO - PROCESS | 25724 | RSS: Main: 209063936 21:35:30 INFO - PROCESS | 25724 | 21:35:33 INFO - PROCESS | 25724 | Cycle 1(1): loaded http://localhost:35847/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35847/tests/kraken/driver.html?testName=json-parse-financial) 21:35:34 INFO - PROCESS | 25724 | RSS: Main: 208322560 21:35:34 INFO - PROCESS | 25724 | 21:35:35 INFO - PROCESS | 25724 | Cycle 1(1): loaded http://localhost:35847/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35847/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:35:36 INFO - PROCESS | 25724 | RSS: Main: 171208704 21:35:36 INFO - PROCESS | 25724 | 21:35:38 INFO - PROCESS | 25724 | Cycle 1(1): loaded http://localhost:35847/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35847/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:35:38 INFO - PROCESS | 25724 | RSS: Main: 178212864 21:35:38 INFO - PROCESS | 25724 | 21:35:40 INFO - PROCESS | 25724 | Cycle 1(1): loaded http://localhost:35847/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35847/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:35:41 INFO - PROCESS | 25724 | RSS: Main: 183197696 21:35:41 INFO - PROCESS | 25724 | 21:35:43 INFO - PROCESS | 25724 | Cycle 1(1): loaded http://localhost:35847/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35847/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:35:44 INFO - PROCESS | 25724 | RSS: Main: 183422976 21:35:44 INFO - PROCESS | 25724 | 21:35:46 INFO - PROCESS | 25724 | Cycle 1(1): loaded http://localhost:35847/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35847/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:35:47 INFO - PROCESS | 25724 | RSS: Main: 182247424 21:35:47 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | Cycle 1(1): loaded http://localhost:35847/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35847/tests/kraken/driver.html?testName=ai-astar) 21:35:49 INFO - PROCESS | 25724 | RSS: Main: 232873984 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | __start_tp_report 21:35:49 INFO - PROCESS | 25724 | _x_x_mozilla_page_load 21:35:49 INFO - PROCESS | 25724 | _x_x_mozilla_page_load_details 21:35:49 INFO - PROCESS | 25724 | |i|pagename|runs| 21:35:49 INFO - PROCESS | 25724 | |0;ai-astar;108;109;108;110;111;109;110;110;110;110 21:35:49 INFO - PROCESS | 25724 | |1;audio-beat-detection;149;120;117;148;116;120;119;117;154;117 21:35:49 INFO - PROCESS | 25724 | |2;audio-dft;162;159;161;161;160;162;163;163;160;160 21:35:49 INFO - PROCESS | 25724 | |3;audio-fft;75;76;76;118;76;77;76;78;96;75 21:35:49 INFO - PROCESS | 25724 | |4;audio-oscillator;82;82;108;81;81;82;100;82;82;110 21:35:49 INFO - PROCESS | 25724 | |5;imaging-gaussian-blur;116;117;116;116;116;116;117;117;117;117 21:35:49 INFO - PROCESS | 25724 | |6;imaging-darkroom;146;147;146;146;147;146;146;146;146;145 21:35:49 INFO - PROCESS | 25724 | |7;imaging-desaturate;95;95;96;96;94;95;95;95;95;94 21:35:49 INFO - PROCESS | 25724 | |8;json-parse-financial;49;54;49;49;49;49;51;51;50;51 21:35:49 INFO - PROCESS | 25724 | |9;json-stringify-tinderbox;57;58;57;58;58;57;57;58;58;58 21:35:49 INFO - PROCESS | 25724 | |10;stanford-crypto-aes;74;74;73;74;73;73;73;73;74;73 21:35:49 INFO - PROCESS | 25724 | |11;stanford-crypto-ccm;121;120;117;120;116;118;116;125;152;120 21:35:49 INFO - PROCESS | 25724 | |12;stanford-crypto-pbkdf2;153;153;154;152;153;157;159;156;152;159 21:35:49 INFO - PROCESS | 25724 | |13;stanford-crypto-sha256-iterative;64;61;71;62;61;61;61;63;62;65 21:35:49 INFO - PROCESS | 25724 | __end_tp_report 21:35:49 INFO - PROCESS | 25724 | __start_cc_report 21:35:49 INFO - PROCESS | 25724 | _x_x_mozilla_cycle_collect,1586 21:35:49 INFO - PROCESS | 25724 | __end_cc_report 21:35:49 INFO - PROCESS | 25724 | __startTimestamp1474518949475__endTimestamp 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | ------- Summary: start ------- 21:35:49 INFO - PROCESS | 25724 | Number of tests: 14 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:0.97 (0.9%) stddev-sans-first:0.87 21:35:49 INFO - PROCESS | 25724 | Values: 108.0 109.0 108.0 110.0 111.0 109.0 110.0 110.0 110.0 110.0 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:120.00 stddev:15.75 (13.1%) stddev-sans-first:14.70 21:35:49 INFO - PROCESS | 25724 | Values: 149.0 120.0 117.0 148.0 116.0 120.0 119.0 117.0 154.0 117.0 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:1.37 (0.8%) stddev-sans-first:1.41 21:35:49 INFO - PROCESS | 25724 | Values: 162.0 159.0 161.0 161.0 160.0 162.0 163.0 163.0 160.0 160.0 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | [#3] audio-fft Cycles:10 Average:82.30 Median:76.00 stddev:14.04 (18.5%) stddev-sans-first:14.64 21:35:49 INFO - PROCESS | 25724 | Values: 75.0 76.0 76.0 118.0 76.0 77.0 76.0 78.0 96.0 75.0 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:82.00 stddev:12.00 (14.6%) stddev-sans-first:12.46 21:35:49 INFO - PROCESS | 25724 | Values: 82.0 82.0 108.0 81.0 81.0 82.0 100.0 82.0 82.0 110.0 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 21:35:49 INFO - PROCESS | 25724 | Values: 116.0 117.0 116.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 21:35:49 INFO - PROCESS | 25724 | Values: 146.0 147.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 145.0 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 21:35:49 INFO - PROCESS | 25724 | Values: 95.0 95.0 96.0 96.0 94.0 95.0 95.0 95.0 95.0 94.0 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:50.50 stddev:1.62 (3.2%) stddev-sans-first:1.66 21:35:49 INFO - PROCESS | 25724 | Values: 49.0 54.0 49.0 49.0 49.0 49.0 51.0 51.0 50.0 51.0 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | [#9] json-stringify-tinderbox Cycles:10 Average:57.60 Median:58.00 stddev:0.52 (0.9%) stddev-sans-first:0.50 21:35:49 INFO - PROCESS | 25724 | Values: 57.0 58.0 57.0 58.0 58.0 57.0 57.0 58.0 58.0 58.0 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | [#10] stanford-crypto-aes Cycles:10 Average:73.40 Median:73.50 stddev:0.52 (0.7%) stddev-sans-first:0.50 21:35:49 INFO - PROCESS | 25724 | Values: 74.0 74.0 73.0 74.0 73.0 73.0 73.0 73.0 74.0 73.0 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | [#11] stanford-crypto-ccm Cycles:10 Average:122.50 Median:120.00 stddev:10.71 (8.9%) stddev-sans-first:11.35 21:35:49 INFO - PROCESS | 25724 | Values: 121.0 120.0 117.0 120.0 116.0 118.0 116.0 125.0 152.0 120.0 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.80 Median:155.00 stddev:2.74 (1.8%) stddev-sans-first:2.83 21:35:49 INFO - PROCESS | 25724 | Values: 153.0 153.0 154.0 152.0 153.0 157.0 159.0 156.0 152.0 159.0 21:35:49 INFO - PROCESS | 25724 | 21:35:49 INFO - PROCESS | 25724 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:62.50 stddev:3.11 (5.0%) stddev-sans-first:3.28 21:35:49 INFO - PROCESS | 25724 | Values: 64.0 61.0 71.0 62.0 61.0 61.0 61.0 63.0 62.0 65.0 21:35:49 INFO - PROCESS | 25724 | -------- Summary: end -------- 21:35:49 INFO - PROCESS | 25724 | 21:35:50 INFO - TEST-INFO | 25724: exit 0 21:35:50 INFO - TEST-OK | kraken | took 59638ms 21:35:50 INFO - SUITE-END | took 1173s 21:35:50 INFO - Completed test suite (00:19:33) 21:35:50 INFO - javascript benchmark 21:35:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3083.0442562805533, 3103.2397062719638, 3124.593802805635, 3142.3558170575034, 3160.4943209086537, 5526.115821468561, 5595.972020139904, 5602.299712535933, 5607.719614019303, 5632.07070833313, 5612.719364031792, 5671.404502527237, 5672.092465205568, 5706.429357064291, 5714.542836573075, 5126.897462050757, 5147.073526765181, 5176.482351764831, 5186.5850731941355, 5229.398619158788, 4998.5001499849905, 5016.949830501703, 5187.19598462239, 5207.661502002376, 5211.765470553815, 5046.495350464961, 5076.543111119999, 5125.743712814363, 5128.230765385185, 5133.614978876599, 5235.083860324447, 5235.55497782689, 5237.607179461536, 5237.659552129099, 5270.841874743764, 4845.951540484621, 5119.513646203604, 5139.357580302462, 5184.922226166611, 5202.167653175474, 5391.32608423947, 5488.423715509894, 5491.121420572689, 5530.751116199781, 5544.251526043973, 5967.61210521319, 5983.820485385445, 6094.116353128771, 6099.542534309944, 6103.115048318014, 5859.091840764689, 5908.822735317947, 6081.057436097412, 6097.146399504073, 6100.115483254903, 5544.112941929272, 5583.05088135018, 5591.4688104629995, 5616.719164041781, 5638.323401191883, 6166.907496387557, 6175.3515880832765, 6176.938230617726, 6177.104319873683, 6180.072989051589, 5679.517241034476, 5731.226284451629, 5911.113333000085, 5915.526757859361, 5918.911216331759, 5863.501602363847, 5887.617304875255, 5892.175095486636, 5954.285485741739, 5972.193753843262, 5910.556708246899, 5910.940890591125, 5914.9704251479325, 5932.2584676915385, 5945.048792193228, 5828.6502809831545, 5868.941310586925, 5926.703664816742, 5945.375735547792, 5964.0, 4564.748938808331, 4583.885402864888, 4622.399088118523, 4626.629869610423, 4688.906221875612, 4084.0810817566403, 4104.630583247463, 4107.363358679409, 4111.8972025699595, 4136.9793151034655, 5776.96014717355, 5779.826605201851, 5782.161586569924, 5787.681677507778, 5820.505257053198, 5842.883142337129, 5883.794067207627, 5887.175795388649, 5910.970445147746, 5976.043832986701, 5555.638883472561, 5557.666540007532, 5606.775728970795, 5696.316442026983, 5730.312362516524, 5201.427842937273, 5210.270562121306, 5211.557017653542, 5211.869703257449, 5212.4266330703595, 6003.6397816131175, 6150.723217455135, 6178.567500274938, 6181.629102253879, 6182.412670796267, 5813.157092221689, 5948.375420580776, 5992.131140984446, 6012.7294271757, 6110.541709371726, 6248.781292654735, 6294.118823364641, 6294.15028971083, 6299.6535190564955, 6304.968475157595, 5729.312482502127, 5829.650220986754, 5889.558283128868, 5894.70526473666, 5927.792527261525, 5885.264341957255, 5980.102984552352, 6000.69996500182, 6049.485793707408, 6119.173911521888, 5956.0, 5958.493528050164, 5961.433663801932, 5965.8806823862415, 5968.313643931004, 5901.616394934315, 5943.613665111754, 5950.19672344228, 5951.196588460502, 5959.702014899324, 5682.602217844877, 5684.545236381162, 5858.297004359334, 5879.029960056543, 5903.0, 6028.4272994065495, 6029.487493563096, 6031.638101713911, 6037.75848966054, 6059.545534084873, 5589.720513974204, 5829.446202610915, 5880.617759845596, 5881.0, 5884.852878678067], "value": 5534.080400080811, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.7694737526051, 621.2234706616729, 656.3731636287345, 660.6531570925266, 672.9360710732478, 34841.3031739365, 47093.76453117734, 48206.27690584635, 48262.276065859, 51108.48891511084, 48776.75611621946, 49148.50851491484, 49224.0, 49263.75368123163, 49299.50700493003, 8574.614142363609, 9185.586648600813, 9339.0, 9344.766380840465, 9347.906520934806, 8476.66093356265, 10866.836997445045, 11126.054285385751, 11144.60993865211, 11501.884981150208, 11185.217034807527, 11230.887691123065, 11256.887431125666, 11259.099272058256, 11278.323300601949, 11028.22802403832, 11074.0, 11081.833772493419, 11091.057260132899, 11092.33445993243, 10946.616868409646, 11005.889941100566, 11015.724606884813, 11022.118230541537, 11025.393603351813, 46257.76871115657, 46375.53624463746, 46429.53570464286, 46582.301265481045, 46622.53377466216, 3778.149916268845, 3964.345882929313, 4000.3599424092004, 4250.723702959329, 4634.39752832133, 34219.144521386916, 34328.48507272393, 34581.481277780855, 34626.6537334626, 36135.819320903225, 4701.2242979908615, 4720.763961801897, 4767.570918617306, 4822.493638167977, 4871.732054736988, 365155.0, 365419.0, 371376.0, 371636.0, 372725.0, 64527.35472645213, 64753.35246647568, 64919.67540162269, 64931.0, 64992.35007649958, 40275.0, 41835.372469412985, 42053.579464204966, 44330.0, 44330.55669443264, 18035.72946405805, 18077.45767626947, 18122.365717199835, 18170.364037258634, 18173.182206800724, 12133.21134126279, 12646.114771965875, 13307.068505204736, 13340.733185336432, 14030.368633411521, 20195.0, 20810.37568872906, 20975.05612247422, 21002.474938126365, 21036.368908932756, 7422.443316751265, 7441.144268409094, 7492.288232617892, 7635.389168866588, 7640.694372225049, 962.7978124593914, 1056.4664844253757, 1063.0645032371617, 1093.9562417503212, 1099.8570185875785, 33857.32285354279, 35228.94867090318, 35611.82194089013, 35646.64353356433, 36157.45763813545, 1055.1400608504046, 1094.8303013032992, 1119.9920071935323, 1148.4487446025955, 1179.5753528729651, 17521.755165917293, 18163.636727265646, 18447.446576602724, 18554.44336669902, 18575.90712046431, 644354.0, 649495.0, 652274.0, 652981.0, 653123.0, 64394.67802660957, 65153.67423162854, 65153.67423162854, 65161.67419162969, 65168.0, 1306.9477220911058, 1343.623785340087, 1368.097055943053, 1436.102435977514, 1452.651363672732, 32533.511997319587, 36728.449073263386, 36794.816025919696, 36814.0, 36839.0, 54363.0, 60969.0, 62920.0, 62931.370686292554, 67659.0, 570015.0, 570976.0, 573508.0, 574742.0, 575400.0, 30772.230694232825, 32734.181645459055, 32749.0, 49089.754551227015, 49430.505694944026, 52344.0, 53123.46876531185, 53506.46493535171, 53622.73188634032, 54815.72592137014, 43623.0, 43721.0, 43994.78002609967, 45079.0, 45391.773041134584, 44839.55160448354, 45453.318200226364, 45470.54529454663, 45970.16119355188, 46387.76806115948, 44546.55453445424, 44852.55147448484, 45533.54466455293, 45861.08277834491, 45981.540184599064, 37509.4373584391, 39255.214895701356, 39437.6056239434, 39860.40209396919, 40222.79888600538, 28959.13122606325, 29349.26626834346, 29396.412071758874, 29611.851940740158, 29626.555601666423], "value": 22193.907571424214, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.9258287216867, 433.0969927700744, 438.2899702481979, 438.93980678721834, 452.88225061484025, 18025.909870450647, 20822.167113315467, 20846.270380536702, 21085.051172697225, 21754.238601648947, 21059.0, 21195.788042119613, 21204.0, 21248.57502849946, 21386.46533836655, 4793.952060479394, 5990.6106103103375, 6043.1841701370195, 6050.788222412217, 6060.3939606039485, 5870.11948207768, 6025.517958563304, 6039.184710064129, 6108.267007959051, 6118.0822876568645, 6014.188084608566, 6021.397860213988, 6026.8794624107495, 6032.125341825433, 6035.064564992434, 5810.506106980891, 6013.1581578579035, 6023.488003519706, 6029.849253768647, 6052.727627256784, 5472.699001554913, 5479.863003424906, 5492.340919089695, 5492.5605951524185, 5497.038018346813, 25005.749942500523, 25369.873150634314, 25382.619260711104, 25431.237062888144, 25463.61804572933, 2482.416632091475, 2492.277239600514, 2558.872056397173, 2570.6144078388206, 2650.3374156460886, 15223.771643425358, 15409.383624655, 15687.529374118794, 15741.370345186178, 16028.839711602852, 1990.7113468547125, 2062.6596611559075, 2093.3824521765964, 2117.184883819725, 2176.173054239379, 63218.683906580634, 65689.67155164242, 81916.59041704812, 83454.0, 85443.57278213631, 11530.192886498033, 11578.305301681927, 11773.411329433495, 11837.289762614271, 12040.458179381949, 17030.31878724862, 17046.573835654206, 17051.658966820592, 17062.146892655317, 17070.0, 8870.15733505316, 8922.86615700765, 8945.37382383227, 9064.138906803842, 9109.18017378439, 8679.609417576225, 8709.303255739525, 8774.473531588124, 8911.777205569782, 8949.239314658196, 7071.575705457586, 7178.712851485987, 7292.645383123717, 7301.525400848928, 7317.926820731831, 1844.7087039072703, 1967.124629539841, 2011.8289945354516, 2107.3467225159943, 2133.2213741984065, 1052.8473371361263, 1074.5346258316865, 1076.2143635146388, 1111.555377848867, 1116.9273997190319, 21130.78869211319, 21344.679829802564, 21350.679739803916, 21474.24840130588, 21499.89250053771, 1076.1337123615408, 1109.9223054386116, 1112.9593829769192, 1117.620009196877, 1147.5467190459722, 11733.0, 12264.570740024057, 12435.378231088409, 12442.253464792142, 12509.0, 486159.0, 490632.0, 492905.0, 505471.0, 548484.0, 63381.68309158517, 92388.53805730928, 112187.07345242877, 113523.0, 114021.42989285167, 1046.3094357723867, 1056.8203405420943, 1080.9027187553158, 1097.1223021582703, 1101.4107452513028, 19099.90450047741, 19330.71003934914, 19349.806501935363, 19513.317033903746, 19531.902340488206, 32055.358892822478, 39435.0, 40105.79947100362, 41262.79368603138, 48391.75804120957, 65417.672911635134, 67730.3226967724, 68344.0, 68432.3156768426, 69537.30462695511, 9444.622215111318, 9446.763830904007, 9511.524423778921, 9551.283653725859, 9661.951690241503, 11414.429278536207, 14063.367148478139, 14253.650392158042, 14470.927645361706, 14700.970932035083, 11900.107491938312, 12416.255024698547, 12513.874861251636, 12689.36553172356, 12808.423620937267, 5962.731677074456, 11089.39008354516, 11617.012553933011, 12254.080943929062, 12622.936885315514, 12191.634250972485, 12225.083118766306, 13033.47866085384, 13101.082924195214, 13324.533641322507, 8963.507007114671, 9074.364794464323, 9138.543072846198, 9150.039245879354, 9330.580123894306, 9597.856032159663, 12554.372281386077, 12969.54606588765, 13015.414306356426, 13110.737785244051], "value": 10024.603393391131, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1541.529833400813, 1578.0768250573415, 1760.3838656470236, 1860.051373799362, 1867.4397680695788, 9130.178283954443, 9825.705228843146, 9841.901580984188, 9865.99999999999, 9872.506374681265, 8231.053428855683, 8505.106963768809, 9131.726048218565, 9467.95266023669, 9656.082672146134, 2482.5531404347257, 2505.0480817289404, 2505.1732928133674, 2514.2080244722947, 2518.5970244760842, 2473.245660073677, 2555.7572030657057, 2556.1309154887335, 2559.846409215453, 2560.7183209846994, 2522.823402361835, 2528.8229823912334, 2545.643609894616, 2549.9490010199784, 2560.398306397995, 2478.7769100781015, 2486.490269494749, 2495.7878580320694, 2500.199936020474, 2517.332906779705, 2474.542209691213, 2478.9752102478924, 2484.6769919910475, 2491.28998235502, 2494.0, 9586.952065239699, 9771.902280977241, 9782.853257201148, 9786.0, 9797.216222702167, 5648.350439699405, 5803.21656576361, 5819.272590926134, 5824.242848429677, 5825.388334224887, 3609.3322735294055, 3692.5568931728367, 3752.5684546277253, 3789.9621003789885, 3808.828602712857, 1226.4235809169677, 1275.7320962597867, 1283.7149178412471, 1300.5513097981182, 1303.8630943750863, 13365.532206372827, 13385.129966552144, 13457.596272111852, 13468.730625387536, 13522.12106213093, 4938.753062346868, 4974.129527332775, 5083.822066227664, 5110.948890511122, 5112.309838171799, 11366.772664546663, 11369.31784092957, 11376.715582110348, 11378.31730096197, 11392.658220253239, 5865.296164460292, 5867.119932010232, 5874.441928016831, 5875.030619947746, 5882.176495290662, 4736.597389221886, 4794.041191761592, 5133.948660513422, 5236.266922630834, 5388.568914486832, 4314.525402205755, 4380.3210502372185, 4389.868303950887, 4492.056668099735, 4523.570260825217, 1413.2792275939346, 1430.7853821926585, 1444.1407362619225, 1457.3806132393902, 1463.1367493179075, 1040.7502199472071, 1049.2078480746986, 1050.5062620568322, 1051.4847724614883, 1065.8294672852307, 6420.775272865428, 6427.132337134521, 6441.774537891245, 6630.204375474877, 6750.459963202932, 485.8049198970572, 492.35009787081424, 504.7274471785268, 517.9352580927384, 518.8495336352491, 4430.556944305608, 4474.172278128492, 4483.237849565582, 4534.229181039232, 4561.5666511682, 11656.475458604213, 12143.149979501526, 12156.452959616661, 12165.270083795001, 12202.938985305016, 11239.157063220127, 11804.232724872856, 11811.468483918245, 12134.453949572291, 12156.574519891938, 8783.209511144029, 8933.776655583662, 8941.239994600532, 8949.373543851867, 8986.0, 6446.9355306448215, 6621.33786621334, 6655.467562595078, 6690.330966903271, 6740.966295168493, 10885.727856803327, 11144.55421783152, 11147.052500537546, 11257.324560526067, 11436.599719009795, 10699.14406847466, 11134.220604557604, 11408.600698975495, 11499.655010349703, 11518.827217591575, 7535.660895259836, 7802.453828232196, 8071.43499955021, 8448.788780280542, 8676.08901065379, 9190.080991900757, 9474.289428292768, 9518.809623807347, 9524.14282714559, 9531.57107930131, 8059.1134975153855, 8262.669493220204, 8305.169483051646, 8312.127226641116, 8633.481991080555, 8030.23712747288, 8038.196180382149, 8243.793905152419, 8338.416310858182, 8445.366597505335, 7702.730404435818, 7776.7278145264645, 7786.688532458638, 7799.18108598589, 7837.843243135219, 5615.326160860723, 5673.602847800779, 5777.364489906191, 5791.986402379483, 6076.1493390924425, 5634.0, 5811.82564523065, 5891.94108058931, 6002.969985150046, 6060.909086363797], "value": 4990.614876409399, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.10565164482216, 359.17569178734806, 359.9064243296742, 365.8335457366898, 366.5875889624171, 5586.41342659021, 5597.3283206015285, 5607.6074674772735, 5653.575981801366, 5690.2887139107615, 5420.268263784388, 5571.387147413781, 5610.887782244363, 5626.368353177584, 5626.578006649497, 4359.950823113148, 4361.579323475462, 4367.825286988516, 4377.78111094444, 4384.027187765502, 4400.97799511002, 4403.427554417937, 4415.8233670653135, 4425.601695847373, 4448.755318457484, 4256.851010214643, 4382.408374869384, 4398.780060996937, 4398.9560104399025, 4401.317795741666, 4305.062721775198, 4348.695591308609, 4349.673774466928, 4358.2808836541935, 4395.7362558246605, 3899.005753532859, 3915.960840391617, 3921.960680419691, 3933.1347103637154, 3939.704522160821, 5479.945200547983, 5489.58828087895, 5491.643043202179, 5494.835154945358, 5523.7238138092935, 4883.243097319921, 4966.47851975541, 5000.799968001275, 5042.873928151789, 5074.695518268915, 5811.593188476766, 5820.330661973842, 5822.097574875901, 5847.275053859122, 5854.18041474194, 4317.870463886088, 4319.984803571189, 4322.719023263509, 4328.9783551082355, 4343.740315308557, 5792.218050563162, 5795.391483894173, 6090.817275481742, 6096.116063170815, 6102.237220347412, 4153.335466325373, 4200.6219440250525, 4275.8075886585175, 4288.656907447396, 4315.935260971088, 4345.130973805251, 4346.717463364871, 4358.825646974149, 4378.496472905656, 4385.846495372647, 4683.601893839062, 4806.404541547595, 4831.637627177975, 4993.90012199754, 5003.4746351633285, 5747.48272655463, 5816.738246778905, 5839.737211825393, 5843.620164689281, 5850.824475265749, 1538.7768773528, 1558.6960542694292, 1560.2978659603225, 1563.1558958162466, 1564.1631727026213, 970.4274478057838, 1017.6133069433567, 1019.1846522781744, 1023.8668973033607, 1030.7629245273629, 5548.500634942875, 5562.249096371938, 5579.944200558024, 5602.411746766612, 5613.445882123624, 5931.73307201177, 5940.346561878277, 5957.6127551709, 5976.850578735566, 5988.191594134736, 5441.483059109379, 5676.801311954062, 5743.655380677173, 5752.194692743019, 5754.1944127822135, 3169.7464202863716, 3180.9840950795096, 3186.6654001329525, 3190.5961376994046, 3192.696693814084, 6085.208922840003, 6148.231471066117, 6212.596181248205, 6253.812385628438, 6254.8123556293385, 6056.0, 6103.542234332443, 6113.23584551931, 6159.291681456601, 6162.2437605282275, 6137.693115344215, 6153.676959453739, 6209.875802484015, 6225.62646241227, 6247.656378899204, 5768.0, 6027.578069535266, 6037.969810150921, 6052.0, 6056.091586262096, 5969.8955693196895, 5997.91003134962, 6007.699615019144, 6037.215162028908, 6042.607230530001, 5926.199963005111, 5981.371955944737, 6135.570510064431, 6154.969225153845, 6206.0, 4334.718243314174, 4353.128154893299, 4376.431063961664, 4408.691391602557, 4411.933820992624, 5894.233749612523, 6087.452129308206, 6162.366972752135, 6174.25908890936, 6178.320384757583, 5843.532517398513, 5927.614705044331, 5932.2584676915385, 5932.970335148297, 5956.791512297049, 5920.437558431942, 5933.910991335047, 5955.642661440327, 5989.191459153133, 6031.276246850406, 5717.199592057199, 5888.793892213752, 5913.733881975236, 5925.911111333419, 5929.584929054925, 5952.613080149777, 5956.642601443928, 5988.8802223956145, 5990.81335312599, 6069.0, 5686.914696279477, 5760.452756988079, 5827.854303642443, 5830.192640281526, 5908.793192238251], "value": 4501.511926800962, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [813.820959388935, 827.346396346886, 869.1395518436749, 872.0712441250072, 911.6490151291753, 15821.288042038108, 16577.585560360996, 16840.410585629474, 17001.65996680069, 17033.148342582866, 15104.244787760596, 15244.84755152448, 15346.155961422117, 16218.918905405457, 16824.83175168248, 718.2243177368442, 735.6836560279078, 861.7285555050171, 878.7934835313728, 891.1355984694835, 869.1873098652779, 873.52829472085, 874.7506960516222, 875.7679215008028, 876.6142897125244, 764.7896828372214, 927.188709878856, 934.1359242700474, 936.0826390137669, 936.3913456253422, 780.4185881518276, 893.1247377569986, 920.3189639666665, 924.5746956400064, 927.5455027036771, 676.3202981004108, 676.3270545806904, 676.7597502886454, 676.7766637009775, 676.939075483204, 16028.519144425685, 16148.515544533566, 16224.594385140348, 16242.0, 16246.837531624651, 15296.0, 15609.45366912164, 15818.050916944905, 15865.20673966297, 16122.516324510283, 14349.282535873164, 14388.0, 14730.410783568645, 14771.409143634242, 15391.230438478031, 4838.903221935541, 4842.66101372904, 4896.167651499253, 4938.851834444972, 4992.251162325644, 81386.18613813905, 81403.59298203471, 81716.59141704314, 82462.0, 86412.13587864104, 12894.548690795777, 13025.348732563334, 13046.739065218642, 13182.802257966137, 13447.125936814076, 22837.429064273194, 23737.643935340984, 23787.762122378554, 25055.749442505705, 25100.874495627406, 9501.524923753923, 9587.472689002172, 9922.702318930444, 9951.104400603981, 10148.543315550818, 5077.441481437039, 5272.736363181903, 5312.362516498045, 5462.562994960394, 5551.916721249185, 4129.876103716893, 4217.831286748496, 4251.043515209115, 4260.808263628144, 4264.424302719155, 742.8959945607619, 748.4947660329256, 754.3135746470795, 757.5606148433895, 769.6036541181296, 760.627292626609, 776.6621519638975, 792.6433105102727, 801.8516574433631, 803.863343231652, 10277.280590358752, 10606.628767993237, 10622.150227981743, 10667.83998240027, 10703.357798531957, 655.7606473637088, 771.3906014248793, 773.8817408844278, 784.6508303804866, 826.09955148888, 5135.12702840518, 5378.300820893259, 5387.8114266001285, 5399.78400863961, 5404.216388623628, 85486.57256713677, 102563.48718256361, 124481.0, 132732.3363383177, 133433.0, 101625.0, 102807.4859625712, 103984.48007759913, 107569.0, 110217.0, 93037.0, 93660.59509107369, 94044.52977735204, 95380.52309738407, 97978.51010744901, 10796.730081748019, 10891.0742586881, 10919.836202457129, 10946.698646157392, 11147.163962702667, 53854.461455384946, 54277.72861135827, 54482.72758636181, 55367.16949245845, 55491.72254138865, 77957.220427795, 81408.0, 83539.0, 84431.57784211039, 86735.0, 12953.481860725466, 14059.718805624036, 14162.929185354007, 14318.14091154534, 14646.048006879519, 15257.923710381378, 15487.148206848731, 15875.682486350439, 15974.68050639004, 16195.595110122342, 14988.2505874708, 15149.242537872842, 15382.461613843461, 15429.459968901483, 15809.13049782273, 14708.057271892943, 15073.924630376778, 15142.167180805161, 15263.618409539851, 15355.923220384273, 14909.3290801912, 14914.105153690813, 15150.090994540364, 15303.464378747136, 15369.769453458432, 12093.75812483763, 12729.172603780724, 12844.678883027998, 12878.356082195665, 12908.290044047668, 11914.940425297818, 12281.570145044881, 12473.438695258554, 12699.682507937006, 13133.408996594984], "value": 8374.422579176557, "unit": "score"}], "name": "dromaeo_css", "value": 7836.876405996505, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 109.0, 108.0, 110.0, 111.0, 109.0, 110.0, 110.0, 110.0, 110.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 120.0, 117.0, 148.0, 116.0, 120.0, 119.0, 117.0, 154.0, 117.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 161.0, 161.0, 160.0, 162.0, 163.0, 163.0, 160.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 118.0, 76.0, 77.0, 76.0, 78.0, 96.0, 75.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 108.0, 81.0, 81.0, 82.0, 100.0, 82.0, 82.0, 110.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 54.0, 49.0, 49.0, 49.0, 49.0, 51.0, 51.0, 50.0, 51.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 57.0, 58.0, 58.0, 57.0, 57.0, 58.0, 58.0, 58.0], "value": 57.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 74.0, 73.0, 73.0, 73.0, 73.0, 74.0, 73.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 120.0, 117.0, 120.0, 116.0, 118.0, 116.0, 125.0, 152.0, 120.0], "value": 122.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 154.0, 152.0, 153.0, 157.0, 159.0, 156.0, 152.0, 159.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 71.0, 62.0, 61.0, 61.0, 61.0, 63.0, 62.0, 65.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1448.8, "alertThreshold": 2.0}]} 21:35:50 INFO - Return code: 0 21:35:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:35:50 INFO - # TBPL SUCCESS # 21:35:50 INFO - Running post-action listener: _resource_record_post_action 21:35:50 INFO - [mozharness: 2016-09-22 04:35:50.787537Z] Finished run-tests step (success) 21:35:50 INFO - Running post-run listener: _resource_record_post_run 21:35:50 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 0; Write bytes: 356143104; Read time: 0; Write time: 281920 21:35:50 INFO - TinderboxPrint: CPU usage
13.0% 21:35:50 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 21:35:50 INFO - TinderboxPrint: I/O write bytes / time
356,143,104 / 281,920 21:35:50 INFO - TinderboxPrint: CPU idle
8,259.0 (87.0%) 21:35:50 INFO - TinderboxPrint: CPU user
1,184.3 (12.5%) 21:35:50 INFO - TinderboxPrint: Swap in / out
0 / 0 21:35:50 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 109518848; Read time: 0; Write time: 116020 21:35:50 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 246620160; Read time: 0; Write time: 165900 21:35:51 INFO - Running post-run listener: _upload_blobber_files 21:35:51 INFO - Blob upload gear active. 21:35:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:35:51 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 21:35:51 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'] 21:35:51 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 21:35:51 INFO - (blobuploader) - INFO - Open directory for files ... 21:35:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:35:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:35:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:35:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:35:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:35:52 INFO - (blobuploader) - INFO - Done attempting. 21:35:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:35:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:35:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:35:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:35:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:35:53 INFO - (blobuploader) - INFO - Done attempting. 21:35:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:35:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:35:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:35:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:35:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:35:56 INFO - (blobuploader) - INFO - Done attempting. 21:35:56 INFO - (blobuploader) - INFO - Iteration through files over. 21:35:56 INFO - Return code: 0 21:35:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:35:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:35:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1cc7fe367d999307f18e5c0189781577110c2ef968767a7b0b542011c467545317b46a0817b4c1a8636999d56ac0a5355d70cfb710d5293ec2a1769e0bc9755", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ebace5905c6096d9b2999884d69202fb4e778e0b1ac7e56a2ab75435236fbd88b8aa2f21ef994aa6c06f764a1877712b89826fd18888883de8476b6a1c582cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64e2d39871ab636ba388f4fc42438353e494b8f5b990109ed4ed40141aca28e7c511a714f95fbff3ddada1e777259e23227ff7fb711df0fb22acc2f3477a3ec6"} 21:35:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:35:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:35:56 INFO - Contents: 21:35:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1cc7fe367d999307f18e5c0189781577110c2ef968767a7b0b542011c467545317b46a0817b4c1a8636999d56ac0a5355d70cfb710d5293ec2a1769e0bc9755", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ebace5905c6096d9b2999884d69202fb4e778e0b1ac7e56a2ab75435236fbd88b8aa2f21ef994aa6c06f764a1877712b89826fd18888883de8476b6a1c582cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64e2d39871ab636ba388f4fc42438353e494b8f5b990109ed4ed40141aca28e7c511a714f95fbff3ddada1e777259e23227ff7fb711df0fb22acc2f3477a3ec6"} 21:35:56 INFO - Running post-run listener: copy_logs_to_upload_dir 21:35:56 INFO - Copying logs to upload dir... 21:35:56 INFO - mkdir: /builds/slave/test/build/upload/logs 21:35:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.030521 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-09-21 21:35:56.190438) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 21:35:56.194065) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429 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/f1cc7fe367d999307f18e5c0189781577110c2ef968767a7b0b542011c467545317b46a0817b4c1a8636999d56ac0a5355d70cfb710d5293ec2a1769e0bc9755", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ebace5905c6096d9b2999884d69202fb4e778e0b1ac7e56a2ab75435236fbd88b8aa2f21ef994aa6c06f764a1877712b89826fd18888883de8476b6a1c582cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64e2d39871ab636ba388f4fc42438353e494b8f5b990109ed4ed40141aca28e7c511a714f95fbff3ddada1e777259e23227ff7fb711df0fb22acc2f3477a3ec6"} build_url:https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011281 build_url: 'https://queue.taskcluster.net/v1/task/PHTFhYj6TzmI5jp5tCMeNQ/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/f1cc7fe367d999307f18e5c0189781577110c2ef968767a7b0b542011c467545317b46a0817b4c1a8636999d56ac0a5355d70cfb710d5293ec2a1769e0bc9755", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7ebace5905c6096d9b2999884d69202fb4e778e0b1ac7e56a2ab75435236fbd88b8aa2f21ef994aa6c06f764a1877712b89826fd18888883de8476b6a1c582cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64e2d39871ab636ba388f4fc42438353e494b8f5b990109ed4ed40141aca28e7c511a714f95fbff3ddada1e777259e23227ff7fb711df0fb22acc2f3477a3ec6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 21:35:56.222233) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 21:35:56.222898) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474517723.102907-465558429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004458 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 21:35:56.306509) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 21:35:56.306860) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 21:35:56.307166) ========= ========= Total master_lag: 0.18 =========