builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-011 starttime: 1474450029.32 results: success (0) buildid: 20160921003000 builduid: 045e51c603e54c44b45d969a13d6e250 revision: 6fb92ddf485702778367f7217caaa216d8639ac3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 02:27:09.320350) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 02:27:09.321170) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 02:27:09.321488) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 02:27:09.395373) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 02:27:09.395673) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-21 02:27:09-- 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% 70.7M=0s 2016-09-21 02:27:09 (70.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112110 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 02:27:09.527326) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 02:27:09.527697) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019761 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 02:27:09.577363) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 02:27:09.577740) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6fb92ddf485702778367f7217caaa216d8639ac3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 6fb92ddf485702778367f7217caaa216d8639ac3 --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=5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-21 02:27:09,809 truncating revision to first 12 chars 2016-09-21 02:27:09,809 Setting DEBUG logging. 2016-09-21 02:27:09,809 attempt 1/10 2016-09-21 02:27:09,809 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6fb92ddf4857?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 02:27:10,829 unpacking tar archive at: fx-team-6fb92ddf4857/testing/mozharness/ program finished with exit code 0 elapsedTime=1.369389 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 02:27:10.964535) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 02:27:10.965265) ========= script_repo_revision: 6fb92ddf485702778367f7217caaa216d8639ac3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 02:27:10.966228) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 02:27:10.966533) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 02:27:10.977419) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-09-21 02:27:10.977849) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:27:11 INFO - MultiFileLogger online at 20160921 02:27:11 in /builds/slave/test 02:27:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 02:27:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:27:11 INFO - {'append_to_log': False, 02:27:11 INFO - 'base_work_dir': '/builds/slave/test', 02:27:11 INFO - 'blob_upload_branch': 'Fx-Team', 02:27:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:27:11 INFO - 'branch': 'Fx-Team', 02:27:11 INFO - 'buildbot_json_path': 'buildprops.json', 02:27:11 INFO - 'config_files': ('talos/linux_config.py',), 02:27:11 INFO - 'default_actions': ('clobber', 02:27:11 INFO - 'read-buildbot-config', 02:27:11 INFO - 'download-and-extract', 02:27:11 INFO - 'populate-webroot', 02:27:11 INFO - 'create-virtualenv', 02:27:11 INFO - 'install', 02:27:11 INFO - 'run-tests'), 02:27:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:27:11 INFO - 'download_minidump_stackwalk': True, 02:27:11 INFO - 'download_symbols': 'ondemand', 02:27:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:27:11 INFO - 'tooltool.py': '/tools/tooltool.py', 02:27:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:27:11 INFO - '/tools/misc-python/virtualenv.py')}, 02:27:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:27:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:27:11 INFO - 'installer_path': 'installer.exe', 02:27:11 INFO - 'log_level': 'info', 02:27:11 INFO - 'log_name': 'talos', 02:27:11 INFO - 'log_to_console': True, 02:27:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:27:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:27:11 INFO - 'opt_config_files': (), 02:27:11 INFO - 'pip_index': False, 02:27:11 INFO - 'sps_profile': False, 02:27:11 INFO - 'sps_profile_interval': 0, 02:27:11 INFO - 'suite': 'dromaeojs', 02:27:11 INFO - 'system_bits': '32', 02:27:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:27:11 INFO - 'title': 'talos-linux64-ix-011', 02:27:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:27:11 INFO - 'use_talos_json': True, 02:27:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:27:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:27:11 INFO - 'work_dir': 'build'} 02:27:11 INFO - [mozharness: 2016-09-21 09:27:11.312772Z] Running clobber step. 02:27:11 INFO - Running pre-action listener: _resource_record_pre_action 02:27:11 INFO - Running main action method: clobber 02:27:11 INFO - rmtree: /builds/slave/test/build 02:27:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:27:12 INFO - Running post-action listener: _resource_record_post_action 02:27:12 INFO - [mozharness: 2016-09-21 09:27:12.299853Z] Finished clobber step (success) 02:27:12 INFO - [mozharness: 2016-09-21 09:27:12.299951Z] Running read-buildbot-config step. 02:27:12 INFO - Running pre-action listener: _resource_record_pre_action 02:27:12 INFO - Running main action method: read_buildbot_config 02:27:12 INFO - Using buildbot properties: 02:27:12 INFO - { 02:27:12 INFO - "project": "", 02:27:12 INFO - "product": "firefox", 02:27:12 INFO - "script_repo_revision": "production", 02:27:12 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 02:27:12 INFO - "repository": "", 02:27:12 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 02:27:12 INFO - "buildid": "20160921003000", 02:27:12 INFO - "pgo_build": "True", 02:27:12 INFO - "basedir": "/builds/slave/test", 02:27:12 INFO - "buildnumber": 1586, 02:27:12 INFO - "slavename": "talos-linux64-ix-011", 02:27:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:27:12 INFO - "platform": "ubuntu64_hw", 02:27:12 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 02:27:12 INFO - "branch": "fx-team", 02:27:12 INFO - "repo_path": "integration/fx-team", 02:27:12 INFO - "revision": "6fb92ddf485702778367f7217caaa216d8639ac3", 02:27:12 INFO - "stage_platform": "linux64-pgo", 02:27:12 INFO - "builduid": "045e51c603e54c44b45d969a13d6e250", 02:27:12 INFO - "slavebuilddir": "test" 02:27:12 INFO - } 02:27:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 02:27:12 INFO - Running post-action listener: _resource_record_post_action 02:27:12 INFO - [mozharness: 2016-09-21 09:27:12.302404Z] Finished read-buildbot-config step (success) 02:27:12 INFO - [mozharness: 2016-09-21 09:27:12.302493Z] Running download-and-extract step. 02:27:12 INFO - Running pre-action listener: _resource_record_pre_action 02:27:12 INFO - Running main action method: download_and_extract 02:27:12 INFO - mkdir: /builds/slave/test/build/tests 02:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:12 INFO - trying https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 02:27:12 INFO - Downloading https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 02:27:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:27:12 INFO - Downloaded 1361 bytes. 02:27:12 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 02:27:12 INFO - Using the following test package requirements: 02:27:12 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 02:27:12 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 02:27:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:27:12 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 02:27:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:27:12 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 02:27:12 INFO - u'jsshell-linux-x86_64.zip'], 02:27:12 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 02:27:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:27:12 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 02:27:12 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 02:27:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:27:12 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 02:27:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:27:12 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 02:27:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:27:12 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 02:27:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:27:12 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:27:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 02:27:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:27:12 INFO - Fetch https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 02:27:13 INFO - Expected file size: 21940519 02:27:17 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:27:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 02:27:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:27:17 INFO - Fetch https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 02:27:18 INFO - Expected file size: 21940519 02:27:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 02:27:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:27:23 INFO - Fetch https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 02:27:23 INFO - Expected file size: 14414059 02:27:24 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')]} 02:27:24 INFO - trying https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 02:27:24 INFO - Downloading https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 02:27:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:27:25 INFO - Downloaded 59580909 bytes. 02:27:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 02:27:25 INFO - mkdir: /builds/slave/test/properties 02:27:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:27:25 INFO - Writing to file /builds/slave/test/properties/build_url 02:27:25 INFO - Contents: 02:27:25 INFO - build_url:https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 02:27:26 INFO - Running post-action listener: _resource_record_post_action 02:27:26 INFO - Running post-action listener: set_extra_try_arguments 02:27:26 INFO - [mozharness: 2016-09-21 09:27:26.329554Z] Finished download-and-extract step (success) 02:27:26 INFO - [mozharness: 2016-09-21 09:27:26.329782Z] Running populate-webroot step. 02:27:26 INFO - Running pre-action listener: _resource_record_pre_action 02:27:26 INFO - Running main action method: populate_webroot 02:27:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:27:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:27:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:27:26 INFO - '--tppagecycles', 02:27:26 INFO - '7'], 02:27:26 INFO - 'tests': ['tsvgm']}}, 02:27:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:27:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:27:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:27:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:27:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:27:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:27:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:26 INFO - 'talos_options': ['--disable-e10s'], 02:27:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:27:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:27:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:26 INFO - 'talos_options': ['--disable-e10s'], 02:27:26 INFO - 'tests': ['damp', 'tps']}, 02:27:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:26 INFO - 'tests': ['damp', 'tps']}, 02:27:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:27:26 INFO - 'tests': ['dromaeo_dom']}, 02:27:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:27:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:27:26 INFO - 'tests': ['basic_compositor_video']}, 02:27:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:27:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:27:26 INFO - 'tests': ['a11yr', 02:27:26 INFO - 'ts_paint', 02:27:26 INFO - 'tpaint', 02:27:26 INFO - 'sessionrestore', 02:27:26 INFO - 'sessionrestore_no_auto_restore', 02:27:26 INFO - 'tabpaint']}, 02:27:26 INFO - 'other-e10s': {'tests': ['a11yr', 02:27:26 INFO - 'ts_paint', 02:27:26 INFO - 'tpaint', 02:27:26 INFO - 'sessionrestore', 02:27:26 INFO - 'sessionrestore_no_auto_restore', 02:27:26 INFO - 'tabpaint']}, 02:27:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:27:26 INFO - 'ts_paint', 02:27:26 INFO - 'tpaint', 02:27:26 INFO - 'sessionrestore', 02:27:26 INFO - 'sessionrestore_no_auto_restore', 02:27:26 INFO - 'tabpaint']}, 02:27:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:27:26 INFO - 'ts_paint', 02:27:26 INFO - 'tpaint', 02:27:26 INFO - 'sessionrestore', 02:27:26 INFO - 'sessionrestore_no_auto_restore', 02:27:26 INFO - 'tabpaint']}, 02:27:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:27:26 INFO - 'tests': ['a11yr', 02:27:26 INFO - 'ts_paint', 02:27:26 INFO - 'tpaint', 02:27:26 INFO - 'sessionrestore', 02:27:26 INFO - 'sessionrestore_no_auto_restore', 02:27:26 INFO - 'tabpaint']}, 02:27:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:27:26 INFO - 'tests': ['a11yr', 02:27:26 INFO - 'ts_paint', 02:27:26 INFO - 'tpaint', 02:27:26 INFO - 'sessionrestore', 02:27:26 INFO - 'sessionrestore_no_auto_restore', 02:27:26 INFO - 'tabpaint']}, 02:27:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:27:26 INFO - 'tests': ['tsvgx', 02:27:26 INFO - 'tsvgr_opacity', 02:27:26 INFO - 'tart', 02:27:26 INFO - 'tscrollx', 02:27:26 INFO - 'cart']}, 02:27:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:27:26 INFO - 'tsvgr_opacity', 02:27:26 INFO - 'tart', 02:27:26 INFO - 'tscrollx', 02:27:26 INFO - 'cart']}, 02:27:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:26 INFO - 'talos_options': ['--disable-e10s'], 02:27:26 INFO - 'tests': ['tp5o']}, 02:27:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:26 INFO - 'tests': ['tp5o']}, 02:27:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:26 INFO - 'talos_options': ['--disable-e10s', 02:27:26 INFO - '--xperf_path', 02:27:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:27:26 INFO - 'tests': ['tp5n']}, 02:27:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:26 INFO - 'talos_options': ['--xperf_path', 02:27:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:27:26 INFO - 'tests': ['tp5n']}}, 02:27:26 INFO - 'talos.zip': {'path': '', 02:27:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:27:26 INFO - Running post-action listener: _resource_record_post_action 02:27:26 INFO - [mozharness: 2016-09-21 09:27:26.345522Z] Finished populate-webroot step (success) 02:27:26 INFO - [mozharness: 2016-09-21 09:27:26.345610Z] Running create-virtualenv step. 02:27:26 INFO - Running pre-action listener: _resource_record_pre_action 02:27:26 INFO - Running main action method: create_virtualenv 02:27:26 INFO - Creating virtualenv /builds/slave/test/build/venv 02:27:26 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 02:27:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:27:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:27:26 INFO - Using real prefix '/usr' 02:27:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:27:26 INFO - Installing distribute.............................................................................................................................................................................................done. 02:27:28 INFO - Installing pip.................done. 02:27:28 INFO - Return code: 0 02:27:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabde091f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe3930>, '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 0xfe4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe4cc0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', '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 02:27:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:27:28 INFO - Using env: {'DISPLAY': ':0', 02:27:28 INFO - 'HOME': '/home/cltbld', 02:27:28 INFO - 'LANG': 'en_US.UTF-8', 02:27:28 INFO - 'LANGUAGE': 'en_US:en', 02:27:28 INFO - 'LOGNAME': 'cltbld', 02:27:28 INFO - 'MAIL': '/var/mail/cltbld', 02:27:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:28 INFO - 'MOZ_NO_REMOTE': '1', 02:27:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:27:28 INFO - 'NO_EM_RESTART': '1', 02:27:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:27:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:28 INFO - 'PWD': '/builds/slave/test', 02:27:28 INFO - 'SHELL': '/bin/bash', 02:27:28 INFO - 'SHLVL': '1', 02:27:28 INFO - 'TERM': 'linux', 02:27:28 INFO - 'TMOUT': '86400', 02:27:28 INFO - 'USER': 'cltbld', 02:27:28 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', 02:27:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:28 INFO - '_': '/tools/buildbot/bin/python'} 02:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:29 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 02:27:29 INFO - Cleaning up... 02:27:29 INFO - Return code: 0 02:27:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:27:29 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')]} 02:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:29 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')]} 02:27:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:29 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 0x7fabde091f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe3930>, '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 0xfe4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe4cc0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', '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 02:27:29 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 02:27:29 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 02:27:29 INFO - Using env: {'DISPLAY': ':0', 02:27:29 INFO - 'HOME': '/home/cltbld', 02:27:29 INFO - 'LANG': 'en_US.UTF-8', 02:27:29 INFO - 'LANGUAGE': 'en_US:en', 02:27:29 INFO - 'LOGNAME': 'cltbld', 02:27:29 INFO - 'MAIL': '/var/mail/cltbld', 02:27:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:29 INFO - 'MOZ_NO_REMOTE': '1', 02:27:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:27:29 INFO - 'NO_EM_RESTART': '1', 02:27:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:27:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:29 INFO - 'PWD': '/builds/slave/test', 02:27:29 INFO - 'SHELL': '/bin/bash', 02:27:29 INFO - 'SHLVL': '1', 02:27:29 INFO - 'TERM': 'linux', 02:27:29 INFO - 'TMOUT': '86400', 02:27:29 INFO - 'USER': 'cltbld', 02:27:29 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', 02:27:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:29 INFO - '_': '/tools/buildbot/bin/python'} 02:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:29 INFO - Downloading/unpacking psutil>=3.1.1 02:27:29 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 02:27:29 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 02:27:29 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 02:27:29 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 02:27:29 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 02:27:29 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 02:27:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:27:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:27:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:27:31 INFO - Installing collected packages: psutil 02:27:31 INFO - Running setup.py install for psutil 02:27:31 INFO - building 'psutil._psutil_linux' extension 02:27:31 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 02:27:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 02:27:31 INFO - building 'psutil._psutil_posix' extension 02:27:31 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 02:27:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 02:27:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:27:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:27:31 INFO - Successfully installed psutil 02:27:31 INFO - Cleaning up... 02:27:31 INFO - Return code: 0 02:27:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:27:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabde091f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe3930>, '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 0xfe4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe4cc0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', '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 02:27:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:27:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:27:31 INFO - Using env: {'DISPLAY': ':0', 02:27:31 INFO - 'HOME': '/home/cltbld', 02:27:31 INFO - 'LANG': 'en_US.UTF-8', 02:27:31 INFO - 'LANGUAGE': 'en_US:en', 02:27:31 INFO - 'LOGNAME': 'cltbld', 02:27:31 INFO - 'MAIL': '/var/mail/cltbld', 02:27:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:31 INFO - 'MOZ_NO_REMOTE': '1', 02:27:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:27:31 INFO - 'NO_EM_RESTART': '1', 02:27:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:27:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:31 INFO - 'PWD': '/builds/slave/test', 02:27:31 INFO - 'SHELL': '/bin/bash', 02:27:31 INFO - 'SHLVL': '1', 02:27:31 INFO - 'TERM': 'linux', 02:27:31 INFO - 'TMOUT': '86400', 02:27:31 INFO - 'USER': 'cltbld', 02:27:31 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', 02:27:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:31 INFO - '_': '/tools/buildbot/bin/python'} 02:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:27:32 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 02:27:32 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 02:27:32 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 02:27:32 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 02:27:32 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 02:27:32 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 02:27:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:27:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:27:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:27:34 INFO - Installing collected packages: mozsystemmonitor 02:27:34 INFO - Running setup.py install for mozsystemmonitor 02:27:34 INFO - Successfully installed mozsystemmonitor 02:27:34 INFO - Cleaning up... 02:27:34 INFO - Return code: 0 02:27:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:27:34 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')]} 02:27:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:34 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')]} 02:27:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:34 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 0x7fabde091f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe3930>, '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 0xfe4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe4cc0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', '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 02:27:34 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 02:27:34 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 02:27:34 INFO - Using env: {'DISPLAY': ':0', 02:27:34 INFO - 'HOME': '/home/cltbld', 02:27:34 INFO - 'LANG': 'en_US.UTF-8', 02:27:34 INFO - 'LANGUAGE': 'en_US:en', 02:27:34 INFO - 'LOGNAME': 'cltbld', 02:27:34 INFO - 'MAIL': '/var/mail/cltbld', 02:27:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:34 INFO - 'MOZ_NO_REMOTE': '1', 02:27:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:27:34 INFO - 'NO_EM_RESTART': '1', 02:27:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:27:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:34 INFO - 'PWD': '/builds/slave/test', 02:27:34 INFO - 'SHELL': '/bin/bash', 02:27:34 INFO - 'SHLVL': '1', 02:27:34 INFO - 'TERM': 'linux', 02:27:34 INFO - 'TMOUT': '86400', 02:27:34 INFO - 'USER': 'cltbld', 02:27:34 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', 02:27:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:34 INFO - '_': '/tools/buildbot/bin/python'} 02:27:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:34 INFO - Downloading/unpacking jsonschema==2.5.1 02:27:34 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 02:27:34 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 02:27:34 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 02:27:34 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 02:27:34 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 02:27:34 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 02:27:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:27:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:36 INFO - Downloading functools32-3.2.3-2.tar.gz 02:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:27:36 INFO - warning: no files found matching '*.txt' 02:27:36 INFO - no previously-included directories found matching 'build' 02:27:36 INFO - no previously-included directories found matching 'dist' 02:27:36 INFO - no previously-included directories found matching '.git*' 02:27:36 INFO - Installing collected packages: jsonschema, functools32 02:27:36 INFO - Running setup.py install for functools32 02:27:36 INFO - warning: no files found matching '*.txt' 02:27:36 INFO - no previously-included directories found matching 'build' 02:27:36 INFO - no previously-included directories found matching 'dist' 02:27:36 INFO - no previously-included directories found matching '.git*' 02:27:36 INFO - Successfully installed jsonschema functools32 02:27:36 INFO - Cleaning up... 02:27:36 INFO - Return code: 0 02:27:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabde091f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe3930>, '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 0xfe4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe4cc0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', '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 02:27:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:27:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:27:36 INFO - Using env: {'DISPLAY': ':0', 02:27:36 INFO - 'HOME': '/home/cltbld', 02:27:36 INFO - 'LANG': 'en_US.UTF-8', 02:27:36 INFO - 'LANGUAGE': 'en_US:en', 02:27:36 INFO - 'LOGNAME': 'cltbld', 02:27:36 INFO - 'MAIL': '/var/mail/cltbld', 02:27:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:36 INFO - 'MOZ_NO_REMOTE': '1', 02:27:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:27:36 INFO - 'NO_EM_RESTART': '1', 02:27:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:27:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:36 INFO - 'PWD': '/builds/slave/test', 02:27:36 INFO - 'SHELL': '/bin/bash', 02:27:36 INFO - 'SHLVL': '1', 02:27:36 INFO - 'TERM': 'linux', 02:27:36 INFO - 'TMOUT': '86400', 02:27:36 INFO - 'USER': 'cltbld', 02:27:36 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', 02:27:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:36 INFO - '_': '/tools/buildbot/bin/python'} 02:27:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:27:36 INFO - Cleaning up... 02:27:37 INFO - Return code: 0 02:27:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:27: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')]} 02:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27: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')]} 02:27:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:37 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 0x7fabde091f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe3930>, '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 0xfe4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe4cc0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', '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 02:27:37 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 02:27:37 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 02:27:37 INFO - Using env: {'DISPLAY': ':0', 02:27:37 INFO - 'HOME': '/home/cltbld', 02:27:37 INFO - 'LANG': 'en_US.UTF-8', 02:27:37 INFO - 'LANGUAGE': 'en_US:en', 02:27:37 INFO - 'LOGNAME': 'cltbld', 02:27:37 INFO - 'MAIL': '/var/mail/cltbld', 02:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:37 INFO - 'MOZ_NO_REMOTE': '1', 02:27:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:27:37 INFO - 'NO_EM_RESTART': '1', 02:27:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:37 INFO - 'PWD': '/builds/slave/test', 02:27:37 INFO - 'SHELL': '/bin/bash', 02:27:37 INFO - 'SHLVL': '1', 02:27:37 INFO - 'TERM': 'linux', 02:27:37 INFO - 'TMOUT': '86400', 02:27:37 INFO - 'USER': 'cltbld', 02:27:37 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', 02:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:37 INFO - '_': '/tools/buildbot/bin/python'} 02:27:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:37 INFO - Downloading/unpacking blobuploader==1.2.4 02:27:37 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 02:27:37 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 02:27:37 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 02:27:37 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 02:27:37 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 02:27:37 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 02:27:39 INFO - Downloading blobuploader-1.2.4.tar.gz 02:27:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:27:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:27:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:39 INFO - Downloading docopt-0.6.1.tar.gz 02:27:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:27:39 INFO - Installing collected packages: blobuploader, requests, docopt 02:27:39 INFO - Running setup.py install for blobuploader 02:27:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:27:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:27:39 INFO - Running setup.py install for requests 02:27:40 INFO - Running setup.py install for docopt 02:27:40 INFO - Successfully installed blobuploader requests docopt 02:27:40 INFO - Cleaning up... 02:27:40 INFO - Return code: 0 02:27:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:27:40 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')]} 02:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:40 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')]} 02:27:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:40 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 0x7fabde091f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe3930>, '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 0xfe4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe4cc0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', '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 02:27:40 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 02:27:40 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 02:27:40 INFO - Using env: {'DISPLAY': ':0', 02:27:40 INFO - 'HOME': '/home/cltbld', 02:27:40 INFO - 'LANG': 'en_US.UTF-8', 02:27:40 INFO - 'LANGUAGE': 'en_US:en', 02:27:40 INFO - 'LOGNAME': 'cltbld', 02:27:40 INFO - 'MAIL': '/var/mail/cltbld', 02:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:40 INFO - 'MOZ_NO_REMOTE': '1', 02:27:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:27:40 INFO - 'NO_EM_RESTART': '1', 02:27:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:40 INFO - 'PWD': '/builds/slave/test', 02:27:40 INFO - 'SHELL': '/bin/bash', 02:27:40 INFO - 'SHLVL': '1', 02:27:40 INFO - 'TERM': 'linux', 02:27:40 INFO - 'TMOUT': '86400', 02:27:40 INFO - 'USER': 'cltbld', 02:27:40 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', 02:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:40 INFO - '_': '/tools/buildbot/bin/python'} 02:27:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:27:40 INFO - Running setup.py (path:/tmp/pip-eCaRwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:27:40 INFO - Running setup.py (path:/tmp/pip-KYePdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:27:40 INFO - Running setup.py (path:/tmp/pip-l6rRQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:27:40 INFO - Running setup.py (path:/tmp/pip-lf5CzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:27:40 INFO - Running setup.py (path:/tmp/pip-qnHJ0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:27:41 INFO - Running setup.py (path:/tmp/pip-nsqbdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:27:41 INFO - Running setup.py (path:/tmp/pip-jS4as1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:27:41 INFO - Running setup.py (path:/tmp/pip-4RqhAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:27:41 INFO - Running setup.py (path:/tmp/pip-nES1JQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:27:41 INFO - Running setup.py (path:/tmp/pip-xGeaEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:27:41 INFO - Running setup.py (path:/tmp/pip-d72N92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:27:41 INFO - Running setup.py (path:/tmp/pip-IR1uo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:27:41 INFO - Running setup.py (path:/tmp/pip-T2zcn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:27:41 INFO - Running setup.py (path:/tmp/pip-5ZqCRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:27:41 INFO - Running setup.py (path:/tmp/pip-PJeOjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:27:41 INFO - Running setup.py (path:/tmp/pip-gOtV2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:27:41 INFO - Running setup.py (path:/tmp/pip-muCjHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:27:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:27:41 INFO - Running setup.py install for manifestparser 02:27:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:27:42 INFO - Running setup.py install for mozcrash 02:27:42 INFO - Running setup.py install for mozdebug 02:27:42 INFO - Running setup.py install for mozdevice 02:27:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:27:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:27:42 INFO - Running setup.py install for mozfile 02:27:42 INFO - Running setup.py install for mozhttpd 02:27:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:27:42 INFO - Running setup.py install for mozinfo 02:27:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:27:42 INFO - Running setup.py install for mozInstall 02:27:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:27:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:27:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:27:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:27:42 INFO - Running setup.py install for mozleak 02:27:42 INFO - Running setup.py install for mozlog 02:27:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:27:42 INFO - Running setup.py install for moznetwork 02:27:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:27:43 INFO - Running setup.py install for mozprocess 02:27:43 INFO - Running setup.py install for mozprofile 02:27:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:27:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:27:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:27:43 INFO - Running setup.py install for mozrunner 02:27:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:27:43 INFO - Running setup.py install for mozscreenshot 02:27:43 INFO - Running setup.py install for moztest 02:27:43 INFO - Running setup.py install for mozversion 02:27:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:27:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:27:43 INFO - Cleaning up... 02:27:43 INFO - Return code: 0 02:27:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:27:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabde091f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe3930>, '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 0xfe4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe4cc0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', '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 02:27:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:27:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:27:43 INFO - Using env: {'DISPLAY': ':0', 02:27:43 INFO - 'HOME': '/home/cltbld', 02:27:43 INFO - 'LANG': 'en_US.UTF-8', 02:27:43 INFO - 'LANGUAGE': 'en_US:en', 02:27:43 INFO - 'LOGNAME': 'cltbld', 02:27:43 INFO - 'MAIL': '/var/mail/cltbld', 02:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:43 INFO - 'MOZ_NO_REMOTE': '1', 02:27:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:27:43 INFO - 'NO_EM_RESTART': '1', 02:27:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:43 INFO - 'PWD': '/builds/slave/test', 02:27:43 INFO - 'SHELL': '/bin/bash', 02:27:43 INFO - 'SHLVL': '1', 02:27:43 INFO - 'TERM': 'linux', 02:27:43 INFO - 'TMOUT': '86400', 02:27:43 INFO - 'USER': 'cltbld', 02:27:43 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', 02:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:43 INFO - '_': '/tools/buildbot/bin/python'} 02:27:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:27:43 INFO - Running setup.py (path:/tmp/pip-qx7pqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:27:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:27:43 INFO - Running setup.py (path:/tmp/pip-XKxKvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:27:44 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)) 02:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:27:44 INFO - Running setup.py (path:/tmp/pip-D2wTjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:27:44 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)) 02:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:27:44 INFO - Running setup.py (path:/tmp/pip-3uDZ9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:27:44 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)) 02:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:27:44 INFO - Running setup.py (path:/tmp/pip-2KoUVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:27:44 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)) 02:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:27:44 INFO - Running setup.py (path:/tmp/pip-4wyvjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:27:44 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)) 02:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:27:44 INFO - Running setup.py (path:/tmp/pip-AdMsD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:27:44 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)) 02:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:27:44 INFO - Running setup.py (path:/tmp/pip-T3cAkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:27:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:27:44 INFO - Running setup.py (path:/tmp/pip-FCjb9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:27:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:27:44 INFO - Running setup.py (path:/tmp/pip-T4GbGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:27:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:27:44 INFO - Running setup.py (path:/tmp/pip-z6v3du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:27:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:27:44 INFO - Running setup.py (path:/tmp/pip-Cwrgg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:27:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:27:44 INFO - Running setup.py (path:/tmp/pip-mljqqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:27:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:27:44 INFO - Running setup.py (path:/tmp/pip-hAYYRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:27:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:27:44 INFO - Running setup.py (path:/tmp/pip-PxrRB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:27:45 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)) 02:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:27:45 INFO - Running setup.py (path:/tmp/pip-5TQQYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:27:45 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)) 02:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:27:45 INFO - Running setup.py (path:/tmp/pip-wdL3NN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:27:45 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)) 02:27:45 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)) 02:27:45 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)) 02:27:45 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)) 02:27:45 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)) 02:27:45 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)) 02:27:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:27: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 02:27: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 02:27: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 02:27: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 02:27: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 02:27: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 02:27:47 INFO - Downloading blessings-1.6.tar.gz 02:27:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:27:47 INFO - Installing collected packages: blessings 02:27:47 INFO - Running setup.py install for blessings 02:27:47 INFO - Successfully installed blessings 02:27:47 INFO - Cleaning up... 02:27:47 INFO - Return code: 0 02:27:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:27:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:27:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:27:47 INFO - Reading from file tmpfile_stdout 02:27:47 INFO - Current package versions: 02:27:47 INFO - argparse == 1.2.1 02:27:47 INFO - blessings == 1.6 02:27:47 INFO - blobuploader == 1.2.4 02:27:47 INFO - docopt == 0.6.1 02:27:47 INFO - functools32 == 3.2.3-2 02:27:47 INFO - jsonschema == 2.5.1 02:27:47 INFO - manifestparser == 1.1 02:27:47 INFO - mozInstall == 1.12 02:27:47 INFO - mozcrash == 0.17 02:27:47 INFO - mozdebug == 0.1 02:27:47 INFO - mozdevice == 0.48 02:27:47 INFO - mozfile == 1.2 02:27:47 INFO - mozhttpd == 0.7 02:27:47 INFO - mozinfo == 0.9 02:27:47 INFO - mozleak == 0.1 02:27:47 INFO - mozlog == 3.4 02:27:47 INFO - moznetwork == 0.27 02:27:47 INFO - mozprocess == 0.23 02:27:47 INFO - mozprofile == 0.28 02:27:47 INFO - mozrunner == 6.12 02:27:47 INFO - mozscreenshot == 0.1 02:27:47 INFO - mozsystemmonitor == 0.3 02:27:47 INFO - moztest == 0.7 02:27:47 INFO - mozversion == 1.4 02:27:47 INFO - psutil == 3.1.1 02:27:47 INFO - requests == 1.2.3 02:27:47 INFO - wsgiref == 0.1.2 02:27:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:27: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')]} 02:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27: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')]} 02:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabde091f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe3930>, '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 0xfe4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe4cc0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', '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 02:27:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:27:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:27:47 INFO - Using env: {'DISPLAY': ':0', 02:27:47 INFO - 'HOME': '/home/cltbld', 02:27:47 INFO - 'LANG': 'en_US.UTF-8', 02:27:47 INFO - 'LANGUAGE': 'en_US:en', 02:27:47 INFO - 'LOGNAME': 'cltbld', 02:27:47 INFO - 'MAIL': '/var/mail/cltbld', 02:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:47 INFO - 'MOZ_NO_REMOTE': '1', 02:27:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:27:47 INFO - 'NO_EM_RESTART': '1', 02:27:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:47 INFO - 'PWD': '/builds/slave/test', 02:27:47 INFO - 'SHELL': '/bin/bash', 02:27:47 INFO - 'SHLVL': '1', 02:27:47 INFO - 'TERM': 'linux', 02:27:47 INFO - 'TMOUT': '86400', 02:27:47 INFO - 'USER': 'cltbld', 02:27:47 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', 02:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:47 INFO - '_': '/tools/buildbot/bin/python'} 02:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:27:47 INFO - Cleaning up... 02:27:47 INFO - Return code: 0 02:27:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:27: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')]} 02:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27: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')]} 02:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabde091f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe3930>, '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 0xfe4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe4cc0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', '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 02:27:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:27: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 02:27:47 INFO - Using env: {'DISPLAY': ':0', 02:27:47 INFO - 'HOME': '/home/cltbld', 02:27:47 INFO - 'LANG': 'en_US.UTF-8', 02:27:47 INFO - 'LANGUAGE': 'en_US:en', 02:27:47 INFO - 'LOGNAME': 'cltbld', 02:27:47 INFO - 'MAIL': '/var/mail/cltbld', 02:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:47 INFO - 'MOZ_NO_REMOTE': '1', 02:27:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:27:47 INFO - 'NO_EM_RESTART': '1', 02:27:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:47 INFO - 'PWD': '/builds/slave/test', 02:27:47 INFO - 'SHELL': '/bin/bash', 02:27:47 INFO - 'SHLVL': '1', 02:27:47 INFO - 'TERM': 'linux', 02:27:47 INFO - 'TMOUT': '86400', 02:27:47 INFO - 'USER': 'cltbld', 02:27:47 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', 02:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:47 INFO - '_': '/tools/buildbot/bin/python'} 02:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:27:47 INFO - Cleaning up... 02:27:47 INFO - Return code: 0 02:27:47 INFO - Running post-action listener: _resource_record_post_action 02:27:47 INFO - Running post-action listener: _start_resource_monitoring 02:27:47 INFO - Starting resource monitoring. 02:27:47 INFO - [mozharness: 2016-09-21 09:27:47.959742Z] Finished create-virtualenv step (success) 02:27:47 INFO - [mozharness: 2016-09-21 09:27:47.960253Z] Running install step. 02:27:47 INFO - Running pre-action listener: _resource_record_pre_action 02:27:47 INFO - Running main action method: install 02:27:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:27:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:27:48 INFO - Reading from file tmpfile_stdout 02:27:48 INFO - Detecting whether we're running mozinstall >=1.0... 02:27:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:27:48 INFO - Reading from file tmpfile_stdout 02:27:48 INFO - Output received: 02:27:48 INFO - Usage: mozinstall [options] installer 02:27:48 INFO - Options: 02:27:48 INFO - -h, --help show this help message and exit 02:27:48 INFO - -d DEST, --destination=DEST 02:27:48 INFO - Directory to install application into. [default: 02:27:48 INFO - "/builds/slave/test"] 02:27:48 INFO - --app=APP Application being installed. [default: firefox] 02:27:48 INFO - mkdir: /builds/slave/test/build/application 02:27:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:28:06 INFO - Reading from file tmpfile_stdout 02:28:06 INFO - Output received: 02:28:06 INFO - /builds/slave/test/build/application/firefox/firefox 02:28:06 INFO - Running post-action listener: _resource_record_post_action 02:28:06 INFO - [mozharness: 2016-09-21 09:28:06.824887Z] Finished install step (success) 02:28:06 INFO - [mozharness: 2016-09-21 09:28:06.825130Z] Running run-tests step. 02:28:06 INFO - Running pre-action listener: _resource_record_pre_action 02:28:06 INFO - Running main action method: run_tests 02:28:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:28:06 INFO - Python 2.7.3 02:28:06 INFO - Return code: 0 02:28:06 INFO - grabbing minidump binary from tooltool 02:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:06 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 0xfe3930>, '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 0xfe4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe4cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:28:06 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 02:28:06 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 02:28:06 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 02:28:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:28:06 INFO - Return code: 0 02:28:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:28:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:28:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:28:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:28:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:28:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 02:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 02:28:06 INFO - Using env: {'DISPLAY': ':0', 02:28:06 INFO - 'HOME': '/home/cltbld', 02:28:06 INFO - 'LANG': 'en_US.UTF-8', 02:28:06 INFO - 'LANGUAGE': 'en_US:en', 02:28:06 INFO - 'LOGNAME': 'cltbld', 02:28:06 INFO - 'MAIL': '/var/mail/cltbld', 02:28:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:28:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:06 INFO - 'MOZ_NO_REMOTE': '1', 02:28:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:28:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:28:06 INFO - 'NO_EM_RESTART': '1', 02:28:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:06 INFO - 'PWD': '/builds/slave/test', 02:28:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:28:06 INFO - 'SHELL': '/bin/bash', 02:28:06 INFO - 'SHLVL': '1', 02:28:06 INFO - 'TERM': 'linux', 02:28:06 INFO - 'TMOUT': '86400', 02:28:06 INFO - 'USER': 'cltbld', 02:28:06 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608', 02:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:06 INFO - '_': '/tools/buildbot/bin/python'} 02:28:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 02:28:07 INFO - mozversion application_buildid: 20160921003000 02:28:07 INFO - mozversion application_changeset: 6fb92ddf485702778367f7217caaa216d8639ac3 02:28:07 INFO - mozversion application_display_name: Nightly 02:28:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:28:07 INFO - mozversion application_name: Firefox 02:28:07 INFO - mozversion application_remotingname: firefox 02:28:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:28:07 INFO - mozversion application_vendor: Mozilla 02:28:07 INFO - mozversion application_version: 52.0a1 02:28:07 INFO - mozversion platform_buildid: 20160921003000 02:28:07 INFO - mozversion platform_changeset: 6fb92ddf485702778367f7217caaa216d8639ac3 02:28:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:28:07 INFO - mozversion platform_version: 52.0a1 02:28:07 INFO - using testdate: 1474450087 02:28:07 INFO - actual date: 1474450087 02:28:07 INFO - starting webserver on 'localhost:53458' 02:28:07 INFO - SUITE-START | Running 2 tests 02:28:07 INFO - TEST-START | dromaeo_css 02:28:07 INFO - operating with platform_type : linux_ 02:28:07 INFO - Initialising browser for dromaeo_css test... 02:28:07 INFO - TEST-INFO | started process 29193 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpX8lLir/profile http://localhost:53458/getInfo.html) 02:28:11 INFO - PROCESS | 29193 | __metrics Screen width/height:1600/1200 02:28:11 INFO - PROCESS | 29193 | colorDepth:24 02:28:11 INFO - PROCESS | 29193 | Browser inner width/height: 1024/697 02:28:11 INFO - PROCESS | 29193 | __metrics 02:28:12 INFO - PROCESS | 29193 | 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" 02:28:12 INFO - PROCESS | 29193 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:28:13 INFO - PROCESS | 29193 | console.error: PushService: 02:28:13 INFO - PROCESS | 29193 | stateChangeProcessEnqueue: Error transitioning state 02:28:13 INFO - PROCESS | 29193 | UnknownError 02:28:13 INFO - TEST-INFO | 29193: exit 0 02:28:13 INFO - Browser initialized. 02:28:13 INFO - Running cycle 1/1 for dromaeo_css test... 02:28:14 INFO - TEST-INFO | started process 29276 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpX8lLir/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:28:26 INFO - PROCESS | 29276 | RSS: Main: 173584384 02:28:26 INFO - PROCESS | 29276 | 02:28:35 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 02:28:36 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 2.01672 seconds. 02:28:44 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 02:29:00 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 02:29:05 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 02:29:09 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 02:29:17 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 02:29:17 INFO - PROCESS | 29276 | 1474450157514 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] 02:29:26 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 02:29:29 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 02:29:32 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.16671 seconds. 02:29:46 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 02:29:54 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 2.05003 seconds. 02:29:54 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 2.05003 seconds. 02:30:01 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 02:30:05 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 02:30:17 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 02:30:23 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:30:34 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 02:30:47 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 02:31:09 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 02:31:10 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 2.05003 seconds. 02:31:23 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 02:31:25 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.15 seconds. 02:31:27 INFO - PROCESS | 29276 | Cycle 1(1): loaded http://localhost:53458/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53458/tests/dromaeo/cssquery-ext.html) 02:31:28 INFO - PROCESS | 29276 | RSS: Main: 290463744 02:31:28 INFO - PROCESS | 29276 | 02:31:37 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 02:31:55 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 02:32:03 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:32:27 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:32:33 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:32:53 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 02:32:59 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 02:33:09 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 02:33:13 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 02:33:23 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 02:33:28 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 02:33:38 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 02:33:44 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:33:46 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 02:33:56 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 02:33:58 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 02:34:16 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 02:34:21 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 02:34:30 INFO - PROCESS | 29276 | Cycle 1(1): loaded http://localhost:53458/tests/dromaeo/cssquery-ext.html (next: http://localhost:53458/tests/dromaeo/cssquery-jquery.html) 02:34:31 INFO - PROCESS | 29276 | RSS: Main: 277499904 02:34:31 INFO - PROCESS | 29276 | 02:34:34 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 02:34:37 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.05002 seconds. 02:34:46 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:34:48 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 02:34:50 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 02:34:55 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 02:35:16 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:35:25 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 02:35:28 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 02:35:37 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 02:35:42 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 02:35:47 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 02:35:50 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:36:00 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 02:36:08 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:36:16 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 02:36:35 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 02:36:39 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:37:10 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 02:37:13 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01678 seconds. 02:37:15 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 02:37:23 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 02:37:25 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 02:37:27 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 02:37:33 INFO - PROCESS | 29276 | Cycle 1(1): loaded http://localhost:53458/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53458/tests/dromaeo/cssquery-mootools.html) 02:37:34 INFO - PROCESS | 29276 | RSS: Main: 216035328 02:37:34 INFO - PROCESS | 29276 | 02:37:37 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 02:37:48 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 02:37:58 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 02:38:33 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 02:38:45 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 02:38:47 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:39:03 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 02:39:21 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 02:39:29 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:39:31 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:39:40 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:39:47 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 02:40:07 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 02:40:29 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 02:40:36 INFO - PROCESS | 29276 | Cycle 1(1): loaded http://localhost:53458/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53458/tests/dromaeo/cssquery-prototype.html) 02:40:36 INFO - PROCESS | 29276 | RSS: Main: 235225088 02:40:36 INFO - PROCESS | 29276 | 02:40:40 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 02:40:42 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.10004 seconds. 02:41:05 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 02:41:20 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 02:41:23 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 02:41:44 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 02:41:56 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 02:42:09 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 02:42:12 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.06674 seconds. 02:42:14 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 02:42:20 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 02:42:28 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 02:42:31 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 02:42:43 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 2.01667 seconds. 02:42:43 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 2.01667 seconds. 02:42:47 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 02:43:17 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 02:43:27 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:43:39 INFO - PROCESS | 29276 | Cycle 1(1): loaded http://localhost:53458/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53458/tests/dromaeo/cssquery-yui.html) 02:43:40 INFO - PROCESS | 29276 | RSS: Main: 221622272 02:43:40 INFO - PROCESS | 29276 | 02:43:45 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 02:43:47 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 02:43:50 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 02:43:52 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 02:43:55 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0168 seconds. 02:44:02 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:44:22 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 02:44:28 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 02:44:41 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:44:45 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 02:44:47 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:45:27 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:45:33 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:45:58 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 02:46:12 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 02:46:15 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 02:46:18 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 02:46:20 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 02:46:26 INFO - PROCESS | 29276 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 02:46:43 INFO - PROCESS | 29276 | Cycle 1(1): loaded http://localhost:53458/tests/dromaeo/cssquery-yui.html (next: http://localhost:53458/tests/dromaeo/cssquery-dojo.html) 02:46:43 INFO - PROCESS | 29276 | RSS: Main: 312274944 02:46:43 INFO - PROCESS | 29276 | 02:46:43 INFO - PROCESS | 29276 | __start_tp_report 02:46:43 INFO - PROCESS | 29276 | _x_x_mozilla_page_load 02:46:43 INFO - PROCESS | 29276 | _x_x_mozilla_page_load_details 02:46:43 INFO - PROCESS | 29276 | |i|pagename|runs| 02:46:43 INFO - PROCESS | 29276 | |0;dojo.html;3011.337505748735;3056.893008744693;3058.189579761364;3091.7526597872175;3109.937801243975;5171.793128274864;5244.475552444758;5275.36695596529;5299.894002119955;5348.438413966531;5218.060749065166;5256.342957130359;5276.287701160342;5277.3139491866095;5316.627836051468;4746.074515469485;4825.662203645747;4834.613230941533;4841.951580484203;4930.482299358569;4906.313116163722;4923.9753801230945;4951.207806750921;4966.9817687374;4986.5013498650205;4719.221328480814;4734.502877197879;4807.375041244616;4817.831375901862;4896.045271172123;4863.076015557032;4870.951290487121;4925.75371231437;4927.876803079916;4958.033183529214;4802.447718512346;4823.638227132944;4847.054824309261;4850.83022094225;4895.485973972717;5078.365204349457;5112;5316.12283973144;5316.813911513079;5380.166074258496;5526.557875370001;5583.3858275589655;5590.021746194399;5600.719964001783;5662.943370566283;5596.188552659883;5612.438756124396;5625.46558076982;5632.07070833312;5635.915461268084;5246.317978662788;5270.472952704776;5277.366715994105;5281.181416880389;5336.813211537577;5619.129034999582;5665.943340566541;5765.538756899438;5857.70711464425;5866.413358664099;5499.670019798825;5547.805826796042;5548.4174161712435;5553.555715542747;5609.242752228479;5592.888142237132;5638.295213098363;5647.209390685307;5654.180143879114;5662.603617746719;5521.171824226398;5548.223248745179;5584.692841893653;5611.747471363717;5679.375268720438;5556.805511807067;5625.015622266087;5648.039833228361;5687.317521897398;5693.971530142406;4384.583464570861;4392.121575684876;4396.49440314368;4435.246008178618;4452.198604251266;3895.552011518655;3909.1204478992;3917.2557214129224;3940.0346915006007;3980.7213495055066;5184.403793563713;5190.273361729361;5212.765425555784;5249.842504724865;5269.973650131725;5510.9448905510435;5513.448655134534;5514.80698175562;5517.641353312102;5518.365387980433;5144.562712169432;5192.091384007784;5193.428722840485;5194;5253.55344795689;4649;4732.929006064912;4772.117158325721;4774.928376074362;4839.032193561302;5838.182654428383;5845.18167456565;5847.181394604759;5847.707614619337;5850.765969361263;5689.54483641316;5694.743736531949;5695.974724549621;5696.544276457957;5698.971505142447;5819.82540523785;5839.562032847469;5851.385604511465;5851.824445266649;5852;5542.251796007487;5588.776448941997;5609.943900560942;5635.6618602883955;5635.88728225425;5670.914936275876;5744.684042377709;5745.6552606843725;5747.683877386784;5747.798827041033;5547.944520554743;5551.583631227755;5553.194786755817;5559.610827242054;5561.499465048165;5437.32033495813;5524.14375771756;5531.640443371168;5542.556595472433;5605.551555875439;5412.702301373462;5474.343078830566;5516.227728117987;5534.612577119563;5608.130739735348;5604.943950560605;5609.943900560942;5610.270664813548;5666.914996275142;5687.20379146928;5472.288602481811;5495.670259784426;5501.779928802803;5502.559795216293;5505.779768809202 02:46:43 INFO - PROCESS | 29276 | |1;ext.html;557.7084336034218;584.7690162385858;611.6972098811086;614.7325913227744;619.244521683546;33175;34063.14842128948;43233.13533729327;44253.336199957026;44423.77788111063;44853.55146448535;45134.774326128405;45193.774031129884;45244.54755452453;45299.320510192294;8828.690995815148;9004.504752238625;9009.819803603925;9013.459192448441;9022.50376229307;8839.60221790021;8901.19889209971;8933.10668933108;8934.463932164059;8937.240334571568;8980.32647551433;9025.774355641095;9028.954855225715;9029.683961061364;9033.187013168814;8927.330450216226;8950.060243674385;8971.641134354619;8979.102089791035;8982.595783189772;8812.691555795516;8883.200511953955;8894.066123057051;8894.332925030583;8901.332400070021;42591;42900.78549607232;42909.78545107286;42936.35595466071;42942;3755.6995440364703;4115.97942010291;4131.9173616527505;4135.379693046037;4207.305794543897;32208.838955805306;32217.83891080553;32294;32329.191770205696;32634.673653263402;4311.353297005443;4410.2282100632265;4470.82116715331;4524.343970124346;4607.470140933802;343055;344493.9999999975;344978;346143;347337;57132.42867571354;57494.7125264371;57673.42326576765;57726.71136644374;57825.421745782005;54910.72544637331;55198;55215.72392138014;55281;55420.72289638526;17163.141842907804;17422.128893555273;17425;17428.302867885395;17435.912820435817;13390.93304533471;13410.463581456828;13454.125481843845;13454.798178027337;13460.394282257126;15454.459093931659;16688.332466701195;16725.581860453585;16756.6648667026;16767.413140540022;6260.154879091356;6345.206849143857;6356.491480681534;6388.680565971683;6399.360063993563;779.2402407652494;839.6305625524872;935.8736570562887;982.3958265666706;1021.6219998600566;29393.559096613568;31459.213519662193;31748.8726465558;32026.679733202836;32295.677043229738;1049.4280617063719;1063.5852017713098;1085.5223701571283;1140.3556990300453;1151.614209239914;16549.25528351227;17113.657726845395;18094.095295235187;18279.451616451526;18345.81654183441;472296;480963;482884;522662;650424;60016;60032;60245;60334.698326508085;60627.393726063056;1220.2922305063041;1267.2016629523525;1271.5740227023866;1279.2004996876951;1343.7446885091802;31369.8431507841;34353.828230858686;34475.65524344725;34679.65320346764;34723.826380868944;55557.444425556845;56074.43925560692;56099;56138.1579276303;56236.71881640566;364933;367387;367647;367896.4815527823;368377;28155.71844281613;28587;28601.42797144087;28780.136595902157;28786.856065719538;48140.759296203294;49567.50432495773;49855.252171218184;53785.731071344395;54978.45021549733;40052.19895602013;41144.1771164581;41427.1714565701;43125;43219.56780432155;39961;40783.796081019405;41172.38241426441;41895.79052104842;42217;34568.13579660529;35153.824230879705;40744;41353;41355.379669305585;33397.66602333946;37302.81348593348;37494.43758343573;37806;37860.29111820845;27285.727142729113;27867.303317417227;28182.859085705262;28287.86848526036;28416.28959275952 02:46:43 INFO - PROCESS | 29276 | |2;jquery.html;380.2831662316536;389.2332105751668;394.262728697336;395.79023117747596;421.3511192763145;13333.466661333547;15211.695766084671;16459.83540164598;18832;19460.832350058972;15455.845441545582;15819.920900395482;15826.366945322174;15843.762343564857;17380.91309543454;5536.833894983147;5774.913376299359;5777.971110144444;5779.248697669318;5787.4212578742;5635.6055076144685;5657.094864821629;5679.858003549903;5806.129080637896;5825.9900334384565;5773.48038676519;5773.855653608652;5775.56683248756;5780.624259423124;5781.161731548923;5643.774249030034;5751.194832723422;5876.236089308405;5909.911351329733;6020.187274717945;5609.49514543693;5809.912851307234;5816.098504731731;5823.301203855522;5827.970860145714;13668.93165534176;14129.85870141306;14186.290685465685;14191.645208869757;14969;2321.141177764221;2413.734489206186;2439.3901524618846;2465.285067330472;2884.8990285339914;10489.842652360221;11048.779024419548;12190.963768079724;13450.663733406645;13483.123596966263;1902.1039922411592;1939.3115444017453;1988.9104990275473;2037.5619241862923;2088.905999230038;79903.60048199721;84928.57535712344;85321;90504.09495905023;91698;10856.891431085745;10992.560297588007;11321.773564528663;11327.716807079889;11356.091512678966;12651.746965060647;13229.603111906657;15955.680886382208;15968;15975.121368324853;7567.621618919032;7706.267904549059;7887.211278872067;8071.071826739882;8076.555789431638;7294.161171465349;7449.068866391701;7504.249575042452;7531.171571127172;8086.3907572683;3991.980040099781;4054.1486287879284;4079;4166.520850102277;4169.187008533368;1944.290334028098;2021.5653634468365;2035.18592562976;2050.7436570428695;2058.547119633678;908.6910450446748;922.423485321674;955.7945041816043;956.416585882611;974.65886939572;17592.384266550864;19079.52301192481;19195.136218870186;19218.903905480664;19499.415017549498;949.6343907595488;982.2534873496195;982.3860087445347;986.1765425869336;1062.0759938853248;10204.846927296096;10557.894421055844;10567.418791966516;10699.304545204537;10706.1435085193;473166;482203;487253;502517;503242;90809;92334.53832730927;95161.82150235034;98777;103188.96811031793;936.681528280388;974.9268804839666;985.5762022330323;990.1187942730975;992.473988785959;16067.27697253603;16501.174941253128;16555.834441655912;16885.493435196964;16966.745498817774;41916;42167.36748948707;42310.78844605757;42453.36319955265;44106;58227.70886145542;59001;59229.703851480466;59341.109883350924;60519.69740151271;7527.548347099191;7539.811504712426;7784.766457006299;7887.171846955987;7976.760697179094;10461.895381046397;10887.673369798918;11220.55117795279;11371.14716396257;11407.942960285145;9698.903010970082;9751.902480975099;9812.215022798016;10254.538545765608;10340.844887326846;9688.370255933341;9885.209183265466;9917.851232231667;10285.74285642865;10597.841032384365;10447.477626118816;10583.735406614589;10598.364098154136;10615.628453004416;10675.519601618102;7893.921060789318;7929.960350198212;7955.2840244378285;8065.274125328749;8102.392320575863;9032.45805251687;10239.436830974368;10263.384196948207;10387.909269526894;10626.415547144672 02:46:43 INFO - PROCESS | 29276 | |3;mootools.html;1327.0975736499176;1455.0542147604053;1482.1255459279023;1576.684663067386;1723.9655206895854;7466.216047315029;8284.33725301976;8593.054763975957;8598.097199794025;8798.384113112084;8604.139586041392;8673.826523469526;8675.39272250941;8681.04508504064;8682.782930426742;2268.1721171772265;2293.472501324696;2297.5634629420356;2297.7127859017623;2302.0906741836966;2294.4378627236265;2309.515001849614;2365.550545396378;2370.182287110944;2372.79831214347;2273.6816845641624;2299.4941112955207;2310.341552657493;2320.106758897828;2328.8951997160084;2248.4378905273684;2249.707538020063;2270.8637481751066;2271.04616061253;2274.56783211191;2231.7433495147943;2266.229481976136;2266.875321857297;2267.5691618592577;2272.0230300970575;8413.915860841375;8549.615267312924;8635.43869648477;8635.654573817039;8638.913610863874;5074.416442109168;5272.077386457393;5283;5291.232771248186;5294.867628309323;3079.3533357994847;3284.655111213336;3321.1697075731067;3342.28140949697;3345.280764635615;1061.828560593588;1068.5405275731432;1070.336391437306;1127.5320741892106;1135.8636963564343;11615.709607259801;11635.76728465426;11661.883381166164;11672.416379181008;11696.941515292454;4141.399497072907;4294.935575966363;4326.740395576213;4482.641388688897;4509.970513268977;9284.350095493386;9288.164065234027;9297.256219502424;9297.72106836796;9298.488583127993;4921.138800709861;4923.113839508851;4930.605551555867;4933.753312334441;4937.753112344369;4207.410962465257;4312.310030395121;4317.546657600969;4471.955280447219;4627.699199552018;3601.1177261571083;3604.387254166798;3734.5518537775642;3755.4179102239195;3800.277947190025;1111.766529028913;1113.298621868234;1128.7291050147905;1145.255583870491;1148.0528563934788;904.6517090920044;905.298393744853;950.6815216902277;964.0793042644258;971.9514024298757;5502.174673798962;5598.692071936001;5615.2700148980375;5760.0783874579865;5957.4042595740075;910.6038873090095;937.6015193542853;955.1212884146602;968.8595153702673;987.7283746969641;3796.3546197145997;3836.7506112102624;3861.478700375413;3954.4661470701662;4013.4983127109113;10418;10729.89270107289;10880.129589632968;10944.945275273572;10957.890421095846;9917.355371900803;10083.49582520871;10888.292261003167;10888.945555272332;10905.890941090645;7716.884246736304;7896.526208427513;7904.960475197588;7907.920920790833;7922.722704705423;5618.606697531133;5663.433656634304;5816.156657284755;5897.882042359214;6007.789727359696;9689.321747477608;9706.272029597667;9766.951165244129;9808.362456440309;9814.312998090065;9395.953020234854;10125.240606954361;10127.24045696561;10134.949325253621;10150.847737283799;6831.316868313129;7050.118735158106;7569.735059272899;7693.422993275639;7709.884351734616;7964.800879978047;8178.386621003329;8192.75421737349;8282.337413007066;8356.707515236938;7449.8882516761205;7565.167831538416;7579;7641.7707468776025;7779.37764978789;7482.588457634664;7484.925150748423;7507;7607;7609.923900760921;6878.105846240157;6994.055802466805;6994.160700715946;6997.545159564612;7002.4398048157045;4662.300654901792;4689.906201876011;4779.211430113992;4841.1285968526;4891.31521586971;4879.292502587176;4915.311856340044;4971.776270067928;5048.798048078036;5121.487851214849 02:46:43 INFO - PROCESS | 29276 | |4;prototype.html;320.21866645385256;331.22989050457676;334.79242869420955;336.79287238348394;339.07094560903124;5100.684555039217;5179.974100129499;5209.244659524366;5242.344706911636;5302.284191634133;5032.295478632995;5097.949020509794;5123.513266239701;5151.381834179903;5152.613553983457;4085.692578374921;4086.264472394968;4095.7747323897174;4106.589341065892;4125.339945608704;3954.8418063277436;4083.3466645336753;4103.4050062741035;4116.876493705178;4118.444010058649;4046.8178931947987;4061.0659548303906;4066.2680717470844;4069.9186016279655;4078.286299897506;4049.979750101231;4060.9593904060876;4061.675065994728;4079;4093.8771836844944;3590.08452844523;3594.101474631342;3604.278769529015;3608.4948107265004;3621.9094522636883;4841.886366135774;5092.083424983502;5094.286799848024;5117.46266642001;5152.536271735562;4603.861884143481;4627.282771170474;4628.4445866496235;4632.2669624921455;4653.115907977473;5394.244805727201;5403.487378369077;5420.891582168374;5423.322084739408;5423.918641220345;3833.2215848419437;3866.420036994445;3871.6515513603913;3914.7846868422225;3935.8228879700196;5606.607537472379;5621.971890140564;5622.943770562283;5624.3250809902665;5624.665763708015;4018.517777866616;4020.4572382728543;4021.9798901005893;4025.677945764332;4039.3940908863906;3976.5228172619472;4052.6555242804693;4073.327900896378;4086.162336720965;4120.690948178899;4579.198640238012;4611.608013318905;4613.861584152481;4620.4686461056735;4633.559811817872;5447.700376479332;5454.509094181542;5470.343558772973;5482.5613950883835;5493.395726470085;1321.2625397602123;1359.422245545639;1363.8840698540537;1388.7986241995056;1396.9930150349182;913.9405938613969;916.170865366844;921.3826736086838;928.8899764781157;938.0947385772762;5150.480608220584;5168.793248270027;5180.740962951837;5183.611229157828;5194.662346947512;5454.509094181542;5475.86441287898;5483.903219356144;5512.641678290898;5513.393526712139;4958.652909286217;5135.871603209875;5166.276721259027;5178.689278643294;5179.8446046618665;2598.701149367809;2598.740125987386;2608.839066615376;2617.9476410471684;2618.3454136465884;5025;5043.033217045355;5045.8738531537;5055.747212639427;5089.9745501272255;4754.809807607657;4988.226824842155;5005.399352077701;5014.222795466709;5026.547610715053;4976.925346119883;5000.2999580058095;5014.665247610371;5019.573336266459;5040.067587496325;4865.148598995091;4884.8290309838985;4903.730294833818;4915;4928.581070609039;4406.774578837666;4769.66612337133;4801.527213484988;4807.423109226916;4821.710697358169;5019.899602008012;5026.623003274842;5034.748262586782;5034.928693497037;5041.344625198701;3608.747387682837;3644.2893635740775;3644.58087319951;3644.653757892996;3646.268022689201;4571.885702857455;4863.294822250825;4868.391451068616;4905.694323667871;4908.410990680998;4697.483276839614;4760.880977975578;4773.451053128934;4780.545848144421;4790.760461976817;4691.695039822401;4695;4733.715977041388;4747.098051370229;4775.33145359643;4506.7070640408265;4571.81712731487;4695.333156729448;4727.456342520654;4758.31004504338;4699.130660827758;4747.905041899212;4800.207965685623;4811.903761924812;4830.734309612864;4726.598239149711;4729.17239483096;4735.674011276848;4737.867959418207;4772.880677983078 02:46:43 INFO - PROCESS | 29276 | |5;yui.html;698.4028655499549;711.9466040046995;734.6547122852257;747.2154238050048;797.4776521378499;15530.223488825555;16605.335786568554;16693.499195024167;16706.58233544162;16712.58218544537;11036.779264414707;11246.557609744166;14099.154050756963;14152.50466233682;14228.71542569148;782.5930516131608;797.6968751874309;800.4356928365488;802.743122200896;803.6704950970103;760.8021914302294;763.3892885691453;764.3808515102756;770.3105720380256;771.12862465414;804.6982381606897;807.3500831830374;807.4347956430522;807.5477732469807;809.9190080991913;800.0519384529351;800.1718221640574;800.1758189065272;800.4676889868231;804.6499772598909;587.3187102960545;589.7788329376484;592.5970340168684;593.4510577715596;597.2981746447906;15233.466828660943;15242.923785381003;15260.923695381563;15267.38930442781;15274.618134546614;15221.31504082308;15404.768928466083;15502.767458488132;15739.44911928077;15942.681146377008;11337.659870203906;11414.201005929588;11460.369679667614;11671;11727.648170554898;3677.9448308275396;3713.461548075541;3757.3800322946954;3897.922041559153;3914.64768170866;83065.29422645256;83097.58451207705;85778;85794.14205857861;86166.56916715376;10416.32293900894;10887.455627218607;11254.831177532344;11324.37715925632;11350.432478376048;20606.896965515076;20619.175232990812;20627.565073509268;20789.79210207909;20841.89579052095;8243.8351232975;8521.275691566287;8637.611307491177;8649.697260595975;8820.11798820113;4192.182524407772;4427.889302767457;4524.547545245515;4659;4938.9012219755405;3471.409860323751;3547.6984456320984;3580.3197392495863;3586.4620306954166;3587.3722098632634;538.2598926476095;545.1958361416957;547.2857920413898;549.8295528386213;570.6091327440716;629.4555209743539;671.2683175338807;718.4683334332557;732.4945787406672;748.1620584944897;8950.776230594158;8961.59672814725;9046.457212567268;9059.456432614064;9225.95387023074;621.9689015549204;647.6632151281308;675.5338816216795;695.1970474102319;701.5930760159096;4001.899952501211;4374.073130907638;4404.141192467502;4434.911301774011;4560.726356418625;120055.73516869351;123167.38416308041;125253;125264;126219.3689031549;64323.678381607795;94898;105122.88629081815;106495.94784443254;106984.46507767568;77129.61435192788;78349;80961.59519202367;86699.56650216835;88737;8711.782205444915;8905.55472226399;8946.910530894867;8948.194662480408;9236.168744813;51074.48925510697;52932.470675294295;53046;54260.609086370445;56093.43906561045;67545.66227168833;68549;76294;77566;78517;12368.381580921097;12593.433295501542;12670.619881403572;12896.161749486253;13135;13414.122022916605;13576.117552359065;13963.720725585228;14034.29828508591;14965.700685986438;12262.325572093621;12678.556250531186;13046.282454465096;13319.866801331862;13484;12745.49018039268;12984.15602985803;13171.07802453819;13260.204387736767;13582.456701732215;12342.876571234532;12346.135770496337;12507.437165327765;12785.232886026868;13053.738925221633;10229.693109206735;10595.629152979609;10704.143668506656;10855.2401331906;10907.29102608328;10133.847992279974;10330.690079297634;10521.316114452842;11582.6525204244;11807.23252988553 02:46:43 INFO - PROCESS | 29276 | __end_tp_report 02:46:43 INFO - PROCESS | 29276 | __start_cc_report 02:46:43 INFO - PROCESS | 29276 | _x_x_mozilla_cycle_collect,1141 02:46:43 INFO - PROCESS | 29276 | __end_cc_report 02:46:43 INFO - PROCESS | 29276 | __startTimestamp1474451203277__endTimestamp 02:46:43 INFO - PROCESS | 29276 | 02:46:43 INFO - PROCESS | 29276 | ------- Summary: start ------- 02:46:43 INFO - PROCESS | 29276 | Number of tests: 6 02:46:43 INFO - PROCESS | 29276 | 02:46:43 INFO - PROCESS | 29276 | [#0] dojo.html Cycles:165 Average:5251.95 Median:5502.56 stddev:581.51 (10.6%) stddev-sans-first:556.09 02:46:43 INFO - PROCESS | 29276 | Values: 3011.3 3056.9 3058.2 3091.8 3109.9 5171.8 5244.5 5275.4 5299.9 5348.4 5218.1 5256.3 5276.3 5277.3 5316.6 4746.1 4825.7 4834.6 4842.0 4930.5 4906.3 4924.0 4951.2 4967.0 4986.5 4719.2 4734.5 4807.4 4817.8 4896.0 4863.1 4871.0 4925.8 4927.9 4958.0 4802.4 4823.6 4847.1 4850.8 4895.5 5078.4 5112.0 5316.1 5316.8 5380.2 5526.6 5583.4 5590.0 5600.7 5662.9 5596.2 5612.4 5625.5 5632.1 5635.9 5246.3 5270.5 5277.4 5281.2 5336.8 5619.1 5665.9 5765.5 5857.7 5866.4 5499.7 5547.8 5548.4 5553.6 5609.2 5592.9 5638.3 5647.2 5654.2 5662.6 5521.2 5548.2 5584.7 5611.7 5679.4 5556.8 5625.0 5648.0 5687.3 5694.0 4384.6 4392.1 4396.5 4435.2 4452.2 3895.6 3909.1 3917.3 3940.0 3980.7 5184.4 5190.3 5212.8 5249.8 5270.0 5510.9 5513.4 5514.8 5517.6 5518.4 5144.6 5192.1 5193.4 5194.0 5253.6 4649.0 4732.9 4772.1 4774.9 4839.0 5838.2 5845.2 5847.2 5847.7 5850.8 5689.5 5694.7 5696.0 5696.5 5699.0 5819.8 5839.6 5851.4 5851.8 5852.0 5542.3 5588.8 5609.9 5635.7 5635.9 5670.9 5744.7 5745.7 5747.7 5747.8 5547.9 5551.6 5553.2 5559.6 5561.5 5437.3 5524.1 5531.6 5542.6 5605.6 5412.7 5474.3 5516.2 5534.6 5608.1 5604.9 5609.9 5610.3 5666.9 5687.2 5472.3 5495.7 5501.8 5502.6 5505.8 02:46:43 INFO - PROCESS | 29276 | 02:46:43 INFO - PROCESS | 29276 | [#1] ext.html Cycles:180 Average:57876.52 Median:42404.00 stddev:111377.81 (262.7%) stddev-sans-first:111607.11 02:46:43 INFO - PROCESS | 29276 | Values: 557.7 584.8 611.7 614.7 619.2 33175.0 34063.1 43233.1 44253.3 44423.8 44853.6 45134.8 45193.8 45244.5 45299.3 8828.7 9004.5 9009.8 9013.5 9022.5 8839.6 8901.2 8933.1 8934.5 8937.2 8980.3 9025.8 9029.0 9029.7 9033.2 8927.3 8950.1 8971.6 8979.1 8982.6 8812.7 8883.2 8894.1 8894.3 8901.3 42591.0 42900.8 42909.8 42936.4 42942.0 3755.7 4116.0 4131.9 4135.4 4207.3 32208.8 32217.8 32294.0 32329.2 32634.7 4311.4 4410.2 4470.8 4524.3 4607.5 343055.0 344494.0 344978.0 346143.0 347337.0 57132.4 57494.7 57673.4 57726.7 57825.4 54910.7 55198.0 55215.7 55281.0 55420.7 17163.1 17422.1 17425.0 17428.3 17435.9 13390.9 13410.5 13454.1 13454.8 13460.4 15454.5 16688.3 16725.6 16756.7 16767.4 6260.2 6345.2 6356.5 6388.7 6399.4 779.2 839.6 935.9 982.4 1021.6 29393.6 31459.2 31748.9 32026.7 32295.7 1049.4 1063.6 1085.5 1140.4 1151.6 16549.3 17113.7 18094.1 18279.5 18345.8 472296.0 480963.0 482884.0 522662.0 650424.0 60016.0 60032.0 60245.0 60334.7 60627.4 1220.3 1267.2 1271.6 1279.2 1343.7 31369.8 34353.8 34475.7 34679.7 34723.8 55557.4 56074.4 56099.0 56138.2 56236.7 364933.0 367387.0 367647.0 367896.5 368377.0 28155.7 28587.0 28601.4 28780.1 28786.9 48140.8 49567.5 49855.3 53785.7 54978.5 40052.2 41144.2 41427.2 43125.0 43219.6 39961.0 40783.8 41172.4 41895.8 42217.0 34568.1 35153.8 40744.0 41353.0 41355.4 33397.7 37302.8 37494.4 37806.0 37860.3 27285.7 27867.3 28182.9 28287.9 28416.3 02:46:43 INFO - PROCESS | 29276 | 02:46:43 INFO - PROCESS | 29276 | [#2] jquery.html Cycles:180 Average:28635.35 Median:21294.36 stddev:81094.76 (380.8%) stddev-sans-first:81294.50 02:46:43 INFO - PROCESS | 29276 | Values: 380.3 389.2 394.3 395.8 421.4 13333.5 15211.7 16459.8 18832.0 19460.8 15455.8 15819.9 15826.4 15843.8 17380.9 5536.8 5774.9 5778.0 5779.2 5787.4 5635.6 5657.1 5679.9 5806.1 5826.0 5773.5 5773.9 5775.6 5780.6 5781.2 5643.8 5751.2 5876.2 5909.9 6020.2 5609.5 5809.9 5816.1 5823.3 5828.0 13668.9 14129.9 14186.3 14191.6 14969.0 2321.1 2413.7 2439.4 2465.3 2884.9 10489.8 11048.8 12191.0 13450.7 13483.1 1902.1 1939.3 1988.9 2037.6 2088.9 79903.6 84928.6 85321.0 90504.1 91698.0 10856.9 10992.6 11321.8 11327.7 11356.1 12651.7 13229.6 15955.7 15968.0 15975.1 7567.6 7706.3 7887.2 8071.1 8076.6 7294.2 7449.1 7504.2 7531.2 8086.4 3992.0 4054.1 4079.0 4166.5 4169.2 1944.3 2021.6 2035.2 2050.7 2058.5 908.7 922.4 955.8 956.4 974.7 17592.4 19079.5 19195.1 19218.9 19499.4 949.6 982.3 982.4 986.2 1062.1 10204.8 10557.9 10567.4 10699.3 10706.1 473166.0 482203.0 487253.0 502517.0 503242.0 90809.0 92334.5 95161.8 98777.0 103189.0 936.7 974.9 985.6 990.1 992.5 16067.3 16501.2 16555.8 16885.5 16966.7 41916.0 42167.4 42310.8 42453.4 44106.0 58227.7 59001.0 59229.7 59341.1 60519.7 7527.5 7539.8 7784.8 7887.2 7976.8 10461.9 10887.7 11220.6 11371.1 11407.9 9698.9 9751.9 9812.2 10254.5 10340.8 9688.4 9885.2 9917.9 10285.7 10597.8 10447.5 10583.7 10598.4 10615.6 10675.5 7893.9 7930.0 7955.3 8065.3 8102.4 9032.5 10239.4 10263.4 10387.9 10626.4 02:46:43 INFO - PROCESS | 29276 | 02:46:43 INFO - PROCESS | 29276 | [#3] mootools.html Cycles:180 Average:5590.80 Median:4922.13 stddev:3176.05 (64.5%) stddev-sans-first:3168.79 02:46:43 INFO - PROCESS | 29276 | Values: 1327.1 1455.1 1482.1 1576.7 1724.0 7466.2 8284.3 8593.1 8598.1 8798.4 8604.1 8673.8 8675.4 8681.0 8682.8 2268.2 2293.5 2297.6 2297.7 2302.1 2294.4 2309.5 2365.6 2370.2 2372.8 2273.7 2299.5 2310.3 2320.1 2328.9 2248.4 2249.7 2270.9 2271.0 2274.6 2231.7 2266.2 2266.9 2267.6 2272.0 8413.9 8549.6 8635.4 8635.7 8638.9 5074.4 5272.1 5283.0 5291.2 5294.9 3079.4 3284.7 3321.2 3342.3 3345.3 1061.8 1068.5 1070.3 1127.5 1135.9 11615.7 11635.8 11661.9 11672.4 11696.9 4141.4 4294.9 4326.7 4482.6 4510.0 9284.4 9288.2 9297.3 9297.7 9298.5 4921.1 4923.1 4930.6 4933.8 4937.8 4207.4 4312.3 4317.5 4472.0 4627.7 3601.1 3604.4 3734.6 3755.4 3800.3 1111.8 1113.3 1128.7 1145.3 1148.1 904.7 905.3 950.7 964.1 972.0 5502.2 5598.7 5615.3 5760.1 5957.4 910.6 937.6 955.1 968.9 987.7 3796.4 3836.8 3861.5 3954.5 4013.5 10418.0 10729.9 10880.1 10944.9 10957.9 9917.4 10083.5 10888.3 10888.9 10905.9 7716.9 7896.5 7905.0 7907.9 7922.7 5618.6 5663.4 5816.2 5897.9 6007.8 9689.3 9706.3 9767.0 9808.4 9814.3 9396.0 10125.2 10127.2 10134.9 10150.8 6831.3 7050.1 7569.7 7693.4 7709.9 7964.8 8178.4 8192.8 8282.3 8356.7 7449.9 7565.2 7579.0 7641.8 7779.4 7482.6 7484.9 7507.0 7607.0 7609.9 6878.1 6994.1 6994.2 6997.5 7002.4 4662.3 4689.9 4779.2 4841.1 4891.3 4879.3 4915.3 4971.8 5048.8 5121.5 02:46:43 INFO - PROCESS | 29276 | 02:46:43 INFO - PROCESS | 29276 | [#4] prototype.html Cycles:180 Average:4328.26 Median:4771.27 stddev:1221.03 (25.6%) stddev-sans-first:1186.82 02:46:43 INFO - PROCESS | 29276 | Values: 320.2 331.2 334.8 336.8 339.1 5100.7 5180.0 5209.2 5242.3 5302.3 5032.3 5097.9 5123.5 5151.4 5152.6 4085.7 4086.3 4095.8 4106.6 4125.3 3954.8 4083.3 4103.4 4116.9 4118.4 4046.8 4061.1 4066.3 4069.9 4078.3 4050.0 4061.0 4061.7 4079.0 4093.9 3590.1 3594.1 3604.3 3608.5 3621.9 4841.9 5092.1 5094.3 5117.5 5152.5 4603.9 4627.3 4628.4 4632.3 4653.1 5394.2 5403.5 5420.9 5423.3 5423.9 3833.2 3866.4 3871.7 3914.8 3935.8 5606.6 5622.0 5622.9 5624.3 5624.7 4018.5 4020.5 4022.0 4025.7 4039.4 3976.5 4052.7 4073.3 4086.2 4120.7 4579.2 4611.6 4613.9 4620.5 4633.6 5447.7 5454.5 5470.3 5482.6 5493.4 1321.3 1359.4 1363.9 1388.8 1397.0 913.9 916.2 921.4 928.9 938.1 5150.5 5168.8 5180.7 5183.6 5194.7 5454.5 5475.9 5483.9 5512.6 5513.4 4958.7 5135.9 5166.3 5178.7 5179.8 2598.7 2598.7 2608.8 2617.9 2618.3 5025.0 5043.0 5045.9 5055.7 5090.0 4754.8 4988.2 5005.4 5014.2 5026.5 4976.9 5000.3 5014.7 5019.6 5040.1 4865.1 4884.8 4903.7 4915.0 4928.6 4406.8 4769.7 4801.5 4807.4 4821.7 5019.9 5026.6 5034.7 5034.9 5041.3 3608.7 3644.3 3644.6 3644.7 3646.3 4571.9 4863.3 4868.4 4905.7 4908.4 4697.5 4760.9 4773.5 4780.5 4790.8 4691.7 4695.0 4733.7 4747.1 4775.3 4506.7 4571.8 4695.3 4727.5 4758.3 4699.1 4747.9 4800.2 4811.9 4830.7 4726.6 4729.2 4735.7 4737.9 4772.9 02:46:43 INFO - PROCESS | 29276 | 02:46:43 INFO - PROCESS | 29276 | [#5] yui.html Cycles:180 Average:20933.05 Median:4389.11 stddev:30957.25 (705.3%) stddev-sans-first:31006.81 02:46:43 INFO - PROCESS | 29276 | Values: 698.4 711.9 734.7 747.2 797.5 15530.2 16605.3 16693.5 16706.6 16712.6 11036.8 11246.6 14099.2 14152.5 14228.7 782.6 797.7 800.4 802.7 803.7 760.8 763.4 764.4 770.3 771.1 804.7 807.4 807.4 807.5 809.9 800.1 800.2 800.2 800.5 804.6 587.3 589.8 592.6 593.5 597.3 15233.5 15242.9 15260.9 15267.4 15274.6 15221.3 15404.8 15502.8 15739.4 15942.7 11337.7 11414.2 11460.4 11671.0 11727.6 3677.9 3713.5 3757.4 3897.9 3914.6 83065.3 83097.6 85778.0 85794.1 86166.6 10416.3 10887.5 11254.8 11324.4 11350.4 20606.9 20619.2 20627.6 20789.8 20841.9 8243.8 8521.3 8637.6 8649.7 8820.1 4192.2 4427.9 4524.5 4659.0 4938.9 3471.4 3547.7 3580.3 3586.5 3587.4 538.3 545.2 547.3 549.8 570.6 629.5 671.3 718.5 732.5 748.2 8950.8 8961.6 9046.5 9059.5 9226.0 622.0 647.7 675.5 695.2 701.6 4001.9 4374.1 4404.1 4434.9 4560.7 120055.7 123167.4 125253.0 125264.0 126219.4 64323.7 94898.0 105122.9 106495.9 106984.5 77129.6 78349.0 80961.6 86699.6 88737.0 8711.8 8905.6 8946.9 8948.2 9236.2 51074.5 52932.5 53046.0 54260.6 56093.4 67545.7 68549.0 76294.0 77566.0 78517.0 12368.4 12593.4 12670.6 12896.2 13135.0 13414.1 13576.1 13963.7 14034.3 14965.7 12262.3 12678.6 13046.3 13319.9 13484.0 12745.5 12984.2 13171.1 13260.2 13582.5 12342.9 12346.1 12507.4 12785.2 13053.7 10229.7 10595.6 10704.1 10855.2 10907.3 10133.8 10330.7 10521.3 11582.7 11807.2 02:46:43 INFO - PROCESS | 29276 | -------- Summary: end -------- 02:46:43 INFO - PROCESS | 29276 | 02:46:44 INFO - TEST-INFO | 29276: exit 0 02:46:44 INFO - TEST-OK | dromaeo_css | took 1117099ms 02:46:44 INFO - TEST-START | kraken 02:46:44 INFO - operating with platform_type : linux_ 02:46:44 INFO - Initialising browser for kraken test... 02:46:44 INFO - TEST-INFO | started process 29578 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpReEqbz/profile http://localhost:53458/getInfo.html) 02:46:49 INFO - PROCESS | 29578 | __metrics Screen width/height:1600/1200 02:46:49 INFO - PROCESS | 29578 | colorDepth:24 02:46:49 INFO - PROCESS | 29578 | Browser inner width/height: 1024/697 02:46:49 INFO - PROCESS | 29578 | __metrics 02:46:50 INFO - PROCESS | 29578 | 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" 02:46:50 INFO - PROCESS | 29578 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:46:50 INFO - PROCESS | 29578 | console.error: PushService: 02:46:50 INFO - PROCESS | 29578 | stateChangeProcessEnqueue: Error transitioning state 02:46:50 INFO - PROCESS | 29578 | UnknownError 02:46:51 INFO - TEST-INFO | 29578: exit 0 02:46:51 INFO - Browser initialized. 02:46:51 INFO - Running cycle 1/1 for kraken test... 02:46:51 INFO - TEST-INFO | started process 29660 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpReEqbz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:47:03 INFO - PROCESS | 29660 | RSS: Main: 172888064 02:47:03 INFO - PROCESS | 29660 | 02:47:06 INFO - PROCESS | 29660 | Cycle 1(1): loaded http://localhost:53458/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53458/tests/kraken/driver.html?testName=audio-beat-detection) 02:47:07 INFO - PROCESS | 29660 | RSS: Main: 233345024 02:47:07 INFO - PROCESS | 29660 | 02:47:09 INFO - PROCESS | 29660 | Cycle 1(1): loaded http://localhost:53458/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53458/tests/kraken/driver.html?testName=audio-dft) 02:47:10 INFO - PROCESS | 29660 | RSS: Main: 217866240 02:47:10 INFO - PROCESS | 29660 | 02:47:13 INFO - PROCESS | 29660 | Cycle 1(1): loaded http://localhost:53458/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53458/tests/kraken/driver.html?testName=audio-fft) 02:47:14 INFO - PROCESS | 29660 | RSS: Main: 298819584 02:47:14 INFO - PROCESS | 29660 | 02:47:16 INFO - PROCESS | 29660 | Cycle 1(1): loaded http://localhost:53458/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53458/tests/kraken/driver.html?testName=audio-oscillator) 02:47:16 INFO - PROCESS | 29660 | RSS: Main: 208809984 02:47:16 INFO - PROCESS | 29660 | 02:47:18 INFO - PROCESS | 29660 | Cycle 1(1): loaded http://localhost:53458/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53458/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:47:19 INFO - PROCESS | 29660 | RSS: Main: 213061632 02:47:19 INFO - PROCESS | 29660 | 02:47:22 INFO - PROCESS | 29660 | Cycle 1(1): loaded http://localhost:53458/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53458/tests/kraken/driver.html?testName=imaging-darkroom) 02:47:23 INFO - PROCESS | 29660 | RSS: Main: 249659392 02:47:23 INFO - PROCESS | 29660 | 02:47:26 INFO - PROCESS | 29660 | Cycle 1(1): loaded http://localhost:53458/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53458/tests/kraken/driver.html?testName=imaging-desaturate) 02:47:27 INFO - PROCESS | 29660 | RSS: Main: 248651776 02:47:27 INFO - PROCESS | 29660 | 02:47:29 INFO - PROCESS | 29660 | Cycle 1(1): loaded http://localhost:53458/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53458/tests/kraken/driver.html?testName=json-parse-financial) 02:47:30 INFO - PROCESS | 29660 | RSS: Main: 248307712 02:47:30 INFO - PROCESS | 29660 | 02:47:31 INFO - PROCESS | 29660 | Cycle 1(1): loaded http://localhost:53458/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53458/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:47:32 INFO - PROCESS | 29660 | RSS: Main: 210382848 02:47:32 INFO - PROCESS | 29660 | 02:47:34 INFO - PROCESS | 29660 | Cycle 1(1): loaded http://localhost:53458/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53458/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:47:34 INFO - PROCESS | 29660 | RSS: Main: 218087424 02:47:34 INFO - PROCESS | 29660 | 02:47:36 INFO - PROCESS | 29660 | Cycle 1(1): loaded http://localhost:53458/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53458/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:47:37 INFO - PROCESS | 29660 | RSS: Main: 222724096 02:47:37 INFO - PROCESS | 29660 | 02:47:40 INFO - PROCESS | 29660 | Cycle 1(1): loaded http://localhost:53458/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53458/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:47:40 INFO - PROCESS | 29660 | RSS: Main: 223854592 02:47:40 INFO - PROCESS | 29660 | 02:47:43 INFO - PROCESS | 29660 | Cycle 1(1): loaded http://localhost:53458/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53458/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:47:44 INFO - PROCESS | 29660 | RSS: Main: 222306304 02:47:44 INFO - PROCESS | 29660 | 02:47:45 INFO - PROCESS | 29660 | Cycle 1(1): loaded http://localhost:53458/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53458/tests/kraken/driver.html?testName=ai-astar) 02:47:46 INFO - PROCESS | 29660 | RSS: Main: 273616896 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | __start_tp_report 02:47:46 INFO - PROCESS | 29660 | _x_x_mozilla_page_load 02:47:46 INFO - PROCESS | 29660 | _x_x_mozilla_page_load_details 02:47:46 INFO - PROCESS | 29660 | |i|pagename|runs| 02:47:46 INFO - PROCESS | 29660 | |0;ai-astar;109;112;111;109;110;111;113;112;110;111 02:47:46 INFO - PROCESS | 29660 | |1;audio-beat-detection;153;118;119;165;124;120;118;116;153;119 02:47:46 INFO - PROCESS | 29660 | |2;audio-dft;161;161;162;162;161;162;160;160;161;163 02:47:46 INFO - PROCESS | 29660 | |3;audio-fft;76;77;79;110;76;77;77;76;105;75 02:47:46 INFO - PROCESS | 29660 | |4;audio-oscillator;84;82;121;83;83;83;116;84;83;112 02:47:46 INFO - PROCESS | 29660 | |5;imaging-gaussian-blur;117;117;117;117;116;116;117;117;116;116 02:47:46 INFO - PROCESS | 29660 | |6;imaging-darkroom;146;146;146;147;147;147;148;147;146;147 02:47:46 INFO - PROCESS | 29660 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;95;95 02:47:46 INFO - PROCESS | 29660 | |8;json-parse-financial;51;50;51;50;51;51;51;51;51;52 02:47:46 INFO - PROCESS | 29660 | |9;json-stringify-tinderbox;60;61;60;61;62;61;62;61;62;62 02:47:46 INFO - PROCESS | 29660 | |10;stanford-crypto-aes;80;79;79;78;79;84;78;86;78;77 02:47:46 INFO - PROCESS | 29660 | |11;stanford-crypto-ccm;127;125;136;125;124;125;125;124;172;147 02:47:46 INFO - PROCESS | 29660 | |12;stanford-crypto-pbkdf2;168;167;169;164;165;166;166;163;162;162 02:47:46 INFO - PROCESS | 29660 | |13;stanford-crypto-sha256-iterative;67;67;65;66;64;65;65;64;65;63 02:47:46 INFO - PROCESS | 29660 | __end_tp_report 02:47:46 INFO - PROCESS | 29660 | __start_cc_report 02:47:46 INFO - PROCESS | 29660 | _x_x_mozilla_cycle_collect,1650 02:47:46 INFO - PROCESS | 29660 | __end_cc_report 02:47:46 INFO - PROCESS | 29660 | __startTimestamp1474451266051__endTimestamp 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | ------- Summary: start ------- 02:47:46 INFO - PROCESS | 29660 | Number of tests: 14 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:1.32 (1.2%) stddev-sans-first:1.22 02:47:46 INFO - PROCESS | 29660 | Values: 109.0 112.0 111.0 109.0 110.0 111.0 113.0 112.0 110.0 111.0 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | [#1] audio-beat-detection Cycles:10 Average:130.50 Median:122.00 stddev:18.69 (15.3%) stddev-sans-first:17.96 02:47:46 INFO - PROCESS | 29660 | Values: 153.0 118.0 119.0 165.0 124.0 120.0 118.0 116.0 153.0 119.0 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 02:47:46 INFO - PROCESS | 29660 | Values: 161.0 161.0 162.0 162.0 161.0 162.0 160.0 160.0 161.0 163.0 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | [#3] audio-fft Cycles:10 Average:82.80 Median:76.50 stddev:13.11 (17.1%) stddev-sans-first:13.68 02:47:46 INFO - PROCESS | 29660 | Values: 76.0 77.0 79.0 110.0 76.0 77.0 77.0 76.0 105.0 75.0 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:83.00 stddev:16.18 (19.5%) stddev-sans-first:16.83 02:47:46 INFO - PROCESS | 29660 | Values: 84.0 82.0 121.0 83.0 83.0 83.0 116.0 84.0 83.0 112.0 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 02:47:46 INFO - PROCESS | 29660 | Values: 117.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 116.0 116.0 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 02:47:46 INFO - PROCESS | 29660 | Values: 146.0 146.0 146.0 147.0 147.0 147.0 148.0 147.0 146.0 147.0 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 02:47:46 INFO - PROCESS | 29660 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:0.57 (1.1%) stddev-sans-first:0.60 02:47:46 INFO - PROCESS | 29660 | Values: 51.0 50.0 51.0 50.0 51.0 51.0 51.0 51.0 51.0 52.0 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.50 stddev:0.79 (1.3%) stddev-sans-first:0.71 02:47:46 INFO - PROCESS | 29660 | Values: 60.0 61.0 60.0 61.0 62.0 61.0 62.0 61.0 62.0 62.0 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:79.00 stddev:2.90 (3.7%) stddev-sans-first:3.07 02:47:46 INFO - PROCESS | 29660 | Values: 80.0 79.0 79.0 78.0 79.0 84.0 78.0 86.0 78.0 77.0 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | [#11] stanford-crypto-ccm Cycles:10 Average:133.00 Median:126.00 stddev:15.56 (12.4%) stddev-sans-first:16.36 02:47:46 INFO - PROCESS | 29660 | Values: 127.0 125.0 136.0 125.0 124.0 125.0 125.0 124.0 172.0 147.0 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:166.00 stddev:2.44 (1.5%) stddev-sans-first:2.37 02:47:46 INFO - PROCESS | 29660 | Values: 168.0 167.0 169.0 164.0 165.0 166.0 166.0 163.0 162.0 162.0 02:47:46 INFO - PROCESS | 29660 | 02:47:46 INFO - PROCESS | 29660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.00 stddev:1.29 (2.0%) stddev-sans-first:1.17 02:47:46 INFO - PROCESS | 29660 | Values: 67.0 67.0 65.0 66.0 64.0 65.0 65.0 64.0 65.0 63.0 02:47:46 INFO - PROCESS | 29660 | -------- Summary: end -------- 02:47:46 INFO - PROCESS | 29660 | 02:47:47 INFO - TEST-INFO | 29660: exit 0 02:47:47 INFO - TEST-OK | kraken | took 63094ms 02:47:47 INFO - SUITE-END | took 1180s 02:47:47 INFO - Completed test suite (00:19:40) 02:47:47 INFO - javascript benchmark 02:47:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3011.337505748735, 3056.893008744693, 3058.189579761364, 3091.7526597872175, 3109.937801243975, 5171.793128274864, 5244.475552444758, 5275.36695596529, 5299.894002119955, 5348.438413966531, 5218.060749065166, 5256.342957130359, 5276.287701160342, 5277.3139491866095, 5316.627836051468, 4746.074515469485, 4825.662203645747, 4834.613230941533, 4841.951580484203, 4930.482299358569, 4906.313116163722, 4923.9753801230945, 4951.207806750921, 4966.9817687374, 4986.5013498650205, 4719.221328480814, 4734.502877197879, 4807.375041244616, 4817.831375901862, 4896.045271172123, 4863.076015557032, 4870.951290487121, 4925.75371231437, 4927.876803079916, 4958.033183529214, 4802.447718512346, 4823.638227132944, 4847.054824309261, 4850.83022094225, 4895.485973972717, 5078.365204349457, 5112.0, 5316.12283973144, 5316.813911513079, 5380.166074258496, 5526.557875370001, 5583.3858275589655, 5590.021746194399, 5600.719964001783, 5662.943370566283, 5596.188552659883, 5612.438756124396, 5625.46558076982, 5632.07070833312, 5635.915461268084, 5246.317978662788, 5270.472952704776, 5277.366715994105, 5281.181416880389, 5336.813211537577, 5619.129034999582, 5665.943340566541, 5765.538756899438, 5857.70711464425, 5866.413358664099, 5499.670019798825, 5547.805826796042, 5548.4174161712435, 5553.555715542747, 5609.242752228479, 5592.888142237132, 5638.295213098363, 5647.209390685307, 5654.180143879114, 5662.603617746719, 5521.171824226398, 5548.223248745179, 5584.692841893653, 5611.747471363717, 5679.375268720438, 5556.805511807067, 5625.015622266087, 5648.039833228361, 5687.317521897398, 5693.971530142406, 4384.583464570861, 4392.121575684876, 4396.49440314368, 4435.246008178618, 4452.198604251266, 3895.552011518655, 3909.1204478992, 3917.2557214129224, 3940.0346915006007, 3980.7213495055066, 5184.403793563713, 5190.273361729361, 5212.765425555784, 5249.842504724865, 5269.973650131725, 5510.9448905510435, 5513.448655134534, 5514.80698175562, 5517.641353312102, 5518.365387980433, 5144.562712169432, 5192.091384007784, 5193.428722840485, 5194.0, 5253.55344795689, 4649.0, 4732.929006064912, 4772.117158325721, 4774.928376074362, 4839.032193561302, 5838.182654428383, 5845.18167456565, 5847.181394604759, 5847.707614619337, 5850.765969361263, 5689.54483641316, 5694.743736531949, 5695.974724549621, 5696.544276457957, 5698.971505142447, 5819.82540523785, 5839.562032847469, 5851.385604511465, 5851.824445266649, 5852.0, 5542.251796007487, 5588.776448941997, 5609.943900560942, 5635.6618602883955, 5635.88728225425, 5670.914936275876, 5744.684042377709, 5745.6552606843725, 5747.683877386784, 5747.798827041033, 5547.944520554743, 5551.583631227755, 5553.194786755817, 5559.610827242054, 5561.499465048165, 5437.32033495813, 5524.14375771756, 5531.640443371168, 5542.556595472433, 5605.551555875439, 5412.702301373462, 5474.343078830566, 5516.227728117987, 5534.612577119563, 5608.130739735348, 5604.943950560605, 5609.943900560942, 5610.270664813548, 5666.914996275142, 5687.20379146928, 5472.288602481811, 5495.670259784426, 5501.779928802803, 5502.559795216293, 5505.779768809202], "value": 5213.6168354393785, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.7084336034218, 584.7690162385858, 611.6972098811086, 614.7325913227744, 619.244521683546, 33175.0, 34063.14842128948, 43233.13533729327, 44253.336199957026, 44423.77788111063, 44853.55146448535, 45134.774326128405, 45193.774031129884, 45244.54755452453, 45299.320510192294, 8828.690995815148, 9004.504752238625, 9009.819803603925, 9013.459192448441, 9022.50376229307, 8839.60221790021, 8901.19889209971, 8933.10668933108, 8934.463932164059, 8937.240334571568, 8980.32647551433, 9025.774355641095, 9028.954855225715, 9029.683961061364, 9033.187013168814, 8927.330450216226, 8950.060243674385, 8971.641134354619, 8979.102089791035, 8982.595783189772, 8812.691555795516, 8883.200511953955, 8894.066123057051, 8894.332925030583, 8901.332400070021, 42591.0, 42900.78549607232, 42909.78545107286, 42936.35595466071, 42942.0, 3755.6995440364703, 4115.97942010291, 4131.9173616527505, 4135.379693046037, 4207.305794543897, 32208.838955805306, 32217.83891080553, 32294.0, 32329.191770205696, 32634.673653263402, 4311.353297005443, 4410.2282100632265, 4470.82116715331, 4524.343970124346, 4607.470140933802, 343055.0, 344493.9999999975, 344978.0, 346143.0, 347337.0, 57132.42867571354, 57494.7125264371, 57673.42326576765, 57726.71136644374, 57825.421745782005, 54910.72544637331, 55198.0, 55215.72392138014, 55281.0, 55420.72289638526, 17163.141842907804, 17422.128893555273, 17425.0, 17428.302867885395, 17435.912820435817, 13390.93304533471, 13410.463581456828, 13454.125481843845, 13454.798178027337, 13460.394282257126, 15454.459093931659, 16688.332466701195, 16725.581860453585, 16756.6648667026, 16767.413140540022, 6260.154879091356, 6345.206849143857, 6356.491480681534, 6388.680565971683, 6399.360063993563, 779.2402407652494, 839.6305625524872, 935.8736570562887, 982.3958265666706, 1021.6219998600566, 29393.559096613568, 31459.213519662193, 31748.8726465558, 32026.679733202836, 32295.677043229738, 1049.4280617063719, 1063.5852017713098, 1085.5223701571283, 1140.3556990300453, 1151.614209239914, 16549.25528351227, 17113.657726845395, 18094.095295235187, 18279.451616451526, 18345.81654183441, 472296.0, 480963.0, 482884.0, 522662.0, 650424.0, 60016.0, 60032.0, 60245.0, 60334.698326508085, 60627.393726063056, 1220.2922305063041, 1267.2016629523525, 1271.5740227023866, 1279.2004996876951, 1343.7446885091802, 31369.8431507841, 34353.828230858686, 34475.65524344725, 34679.65320346764, 34723.826380868944, 55557.444425556845, 56074.43925560692, 56099.0, 56138.1579276303, 56236.71881640566, 364933.0, 367387.0, 367647.0, 367896.4815527823, 368377.0, 28155.71844281613, 28587.0, 28601.42797144087, 28780.136595902157, 28786.856065719538, 48140.759296203294, 49567.50432495773, 49855.252171218184, 53785.731071344395, 54978.45021549733, 40052.19895602013, 41144.1771164581, 41427.1714565701, 43125.0, 43219.56780432155, 39961.0, 40783.796081019405, 41172.38241426441, 41895.79052104842, 42217.0, 34568.13579660529, 35153.824230879705, 40744.0, 41353.0, 41355.379669305585, 33397.66602333946, 37302.81348593348, 37494.43758343573, 37806.0, 37860.29111820845, 27285.727142729113, 27867.303317417227, 28182.859085705262, 28287.86848526036, 28416.28959275952], "value": 19971.49163520113, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.2831662316536, 389.2332105751668, 394.262728697336, 395.79023117747596, 421.3511192763145, 13333.466661333547, 15211.695766084671, 16459.83540164598, 18832.0, 19460.832350058972, 15455.845441545582, 15819.920900395482, 15826.366945322174, 15843.762343564857, 17380.91309543454, 5536.833894983147, 5774.913376299359, 5777.971110144444, 5779.248697669318, 5787.4212578742, 5635.6055076144685, 5657.094864821629, 5679.858003549903, 5806.129080637896, 5825.9900334384565, 5773.48038676519, 5773.855653608652, 5775.56683248756, 5780.624259423124, 5781.161731548923, 5643.774249030034, 5751.194832723422, 5876.236089308405, 5909.911351329733, 6020.187274717945, 5609.49514543693, 5809.912851307234, 5816.098504731731, 5823.301203855522, 5827.970860145714, 13668.93165534176, 14129.85870141306, 14186.290685465685, 14191.645208869757, 14969.0, 2321.141177764221, 2413.734489206186, 2439.3901524618846, 2465.285067330472, 2884.8990285339914, 10489.842652360221, 11048.779024419548, 12190.963768079724, 13450.663733406645, 13483.123596966263, 1902.1039922411592, 1939.3115444017453, 1988.9104990275473, 2037.5619241862923, 2088.905999230038, 79903.60048199721, 84928.57535712344, 85321.0, 90504.09495905023, 91698.0, 10856.891431085745, 10992.560297588007, 11321.773564528663, 11327.716807079889, 11356.091512678966, 12651.746965060647, 13229.603111906657, 15955.680886382208, 15968.0, 15975.121368324853, 7567.621618919032, 7706.267904549059, 7887.211278872067, 8071.071826739882, 8076.555789431638, 7294.161171465349, 7449.068866391701, 7504.249575042452, 7531.171571127172, 8086.3907572683, 3991.980040099781, 4054.1486287879284, 4079.0, 4166.520850102277, 4169.187008533368, 1944.290334028098, 2021.5653634468365, 2035.18592562976, 2050.7436570428695, 2058.547119633678, 908.6910450446748, 922.423485321674, 955.7945041816043, 956.416585882611, 974.65886939572, 17592.384266550864, 19079.52301192481, 19195.136218870186, 19218.903905480664, 19499.415017549498, 949.6343907595488, 982.2534873496195, 982.3860087445347, 986.1765425869336, 1062.0759938853248, 10204.846927296096, 10557.894421055844, 10567.418791966516, 10699.304545204537, 10706.1435085193, 473166.0, 482203.0, 487253.0, 502517.0, 503242.0, 90809.0, 92334.53832730927, 95161.82150235034, 98777.0, 103188.96811031793, 936.681528280388, 974.9268804839666, 985.5762022330323, 990.1187942730975, 992.473988785959, 16067.27697253603, 16501.174941253128, 16555.834441655912, 16885.493435196964, 16966.745498817774, 41916.0, 42167.36748948707, 42310.78844605757, 42453.36319955265, 44106.0, 58227.70886145542, 59001.0, 59229.703851480466, 59341.109883350924, 60519.69740151271, 7527.548347099191, 7539.811504712426, 7784.766457006299, 7887.171846955987, 7976.760697179094, 10461.895381046397, 10887.673369798918, 11220.55117795279, 11371.14716396257, 11407.942960285145, 9698.903010970082, 9751.902480975099, 9812.215022798016, 10254.538545765608, 10340.844887326846, 9688.370255933341, 9885.209183265466, 9917.851232231667, 10285.74285642865, 10597.841032384365, 10447.477626118816, 10583.735406614589, 10598.364098154136, 10615.628453004416, 10675.519601618102, 7893.921060789318, 7929.960350198212, 7955.2840244378285, 8065.274125328749, 8102.392320575863, 9032.45805251687, 10239.436830974368, 10263.384196948207, 10387.909269526894, 10626.415547144672], "value": 8820.360346809613, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.0975736499176, 1455.0542147604053, 1482.1255459279023, 1576.684663067386, 1723.9655206895854, 7466.216047315029, 8284.33725301976, 8593.054763975957, 8598.097199794025, 8798.384113112084, 8604.139586041392, 8673.826523469526, 8675.39272250941, 8681.04508504064, 8682.782930426742, 2268.1721171772265, 2293.472501324696, 2297.5634629420356, 2297.7127859017623, 2302.0906741836966, 2294.4378627236265, 2309.515001849614, 2365.550545396378, 2370.182287110944, 2372.79831214347, 2273.6816845641624, 2299.4941112955207, 2310.341552657493, 2320.106758897828, 2328.8951997160084, 2248.4378905273684, 2249.707538020063, 2270.8637481751066, 2271.04616061253, 2274.56783211191, 2231.7433495147943, 2266.229481976136, 2266.875321857297, 2267.5691618592577, 2272.0230300970575, 8413.915860841375, 8549.615267312924, 8635.43869648477, 8635.654573817039, 8638.913610863874, 5074.416442109168, 5272.077386457393, 5283.0, 5291.232771248186, 5294.867628309323, 3079.3533357994847, 3284.655111213336, 3321.1697075731067, 3342.28140949697, 3345.280764635615, 1061.828560593588, 1068.5405275731432, 1070.336391437306, 1127.5320741892106, 1135.8636963564343, 11615.709607259801, 11635.76728465426, 11661.883381166164, 11672.416379181008, 11696.941515292454, 4141.399497072907, 4294.935575966363, 4326.740395576213, 4482.641388688897, 4509.970513268977, 9284.350095493386, 9288.164065234027, 9297.256219502424, 9297.72106836796, 9298.488583127993, 4921.138800709861, 4923.113839508851, 4930.605551555867, 4933.753312334441, 4937.753112344369, 4207.410962465257, 4312.310030395121, 4317.546657600969, 4471.955280447219, 4627.699199552018, 3601.1177261571083, 3604.387254166798, 3734.5518537775642, 3755.4179102239195, 3800.277947190025, 1111.766529028913, 1113.298621868234, 1128.7291050147905, 1145.255583870491, 1148.0528563934788, 904.6517090920044, 905.298393744853, 950.6815216902277, 964.0793042644258, 971.9514024298757, 5502.174673798962, 5598.692071936001, 5615.2700148980375, 5760.0783874579865, 5957.4042595740075, 910.6038873090095, 937.6015193542853, 955.1212884146602, 968.8595153702673, 987.7283746969641, 3796.3546197145997, 3836.7506112102624, 3861.478700375413, 3954.4661470701662, 4013.4983127109113, 10418.0, 10729.89270107289, 10880.129589632968, 10944.945275273572, 10957.890421095846, 9917.355371900803, 10083.49582520871, 10888.292261003167, 10888.945555272332, 10905.890941090645, 7716.884246736304, 7896.526208427513, 7904.960475197588, 7907.920920790833, 7922.722704705423, 5618.606697531133, 5663.433656634304, 5816.156657284755, 5897.882042359214, 6007.789727359696, 9689.321747477608, 9706.272029597667, 9766.951165244129, 9808.362456440309, 9814.312998090065, 9395.953020234854, 10125.240606954361, 10127.24045696561, 10134.949325253621, 10150.847737283799, 6831.316868313129, 7050.118735158106, 7569.735059272899, 7693.422993275639, 7709.884351734616, 7964.800879978047, 8178.386621003329, 8192.75421737349, 8282.337413007066, 8356.707515236938, 7449.8882516761205, 7565.167831538416, 7579.0, 7641.7707468776025, 7779.37764978789, 7482.588457634664, 7484.925150748423, 7507.0, 7607.0, 7609.923900760921, 6878.105846240157, 6994.055802466805, 6994.160700715946, 6997.545159564612, 7002.4398048157045, 4662.300654901792, 4689.906201876011, 4779.211430113992, 4841.1285968526, 4891.31521586971, 4879.292502587176, 4915.311856340044, 4971.776270067928, 5048.798048078036, 5121.487851214849], "value": 4477.60007690828, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.21866645385256, 331.22989050457676, 334.79242869420955, 336.79287238348394, 339.07094560903124, 5100.684555039217, 5179.974100129499, 5209.244659524366, 5242.344706911636, 5302.284191634133, 5032.295478632995, 5097.949020509794, 5123.513266239701, 5151.381834179903, 5152.613553983457, 4085.692578374921, 4086.264472394968, 4095.7747323897174, 4106.589341065892, 4125.339945608704, 3954.8418063277436, 4083.3466645336753, 4103.4050062741035, 4116.876493705178, 4118.444010058649, 4046.8178931947987, 4061.0659548303906, 4066.2680717470844, 4069.9186016279655, 4078.286299897506, 4049.979750101231, 4060.9593904060876, 4061.675065994728, 4079.0, 4093.8771836844944, 3590.08452844523, 3594.101474631342, 3604.278769529015, 3608.4948107265004, 3621.9094522636883, 4841.886366135774, 5092.083424983502, 5094.286799848024, 5117.46266642001, 5152.536271735562, 4603.861884143481, 4627.282771170474, 4628.4445866496235, 4632.2669624921455, 4653.115907977473, 5394.244805727201, 5403.487378369077, 5420.891582168374, 5423.322084739408, 5423.918641220345, 3833.2215848419437, 3866.420036994445, 3871.6515513603913, 3914.7846868422225, 3935.8228879700196, 5606.607537472379, 5621.971890140564, 5622.943770562283, 5624.3250809902665, 5624.665763708015, 4018.517777866616, 4020.4572382728543, 4021.9798901005893, 4025.677945764332, 4039.3940908863906, 3976.5228172619472, 4052.6555242804693, 4073.327900896378, 4086.162336720965, 4120.690948178899, 4579.198640238012, 4611.608013318905, 4613.861584152481, 4620.4686461056735, 4633.559811817872, 5447.700376479332, 5454.509094181542, 5470.343558772973, 5482.5613950883835, 5493.395726470085, 1321.2625397602123, 1359.422245545639, 1363.8840698540537, 1388.7986241995056, 1396.9930150349182, 913.9405938613969, 916.170865366844, 921.3826736086838, 928.8899764781157, 938.0947385772762, 5150.480608220584, 5168.793248270027, 5180.740962951837, 5183.611229157828, 5194.662346947512, 5454.509094181542, 5475.86441287898, 5483.903219356144, 5512.641678290898, 5513.393526712139, 4958.652909286217, 5135.871603209875, 5166.276721259027, 5178.689278643294, 5179.8446046618665, 2598.701149367809, 2598.740125987386, 2608.839066615376, 2617.9476410471684, 2618.3454136465884, 5025.0, 5043.033217045355, 5045.8738531537, 5055.747212639427, 5089.9745501272255, 4754.809807607657, 4988.226824842155, 5005.399352077701, 5014.222795466709, 5026.547610715053, 4976.925346119883, 5000.2999580058095, 5014.665247610371, 5019.573336266459, 5040.067587496325, 4865.148598995091, 4884.8290309838985, 4903.730294833818, 4915.0, 4928.581070609039, 4406.774578837666, 4769.66612337133, 4801.527213484988, 4807.423109226916, 4821.710697358169, 5019.899602008012, 5026.623003274842, 5034.748262586782, 5034.928693497037, 5041.344625198701, 3608.747387682837, 3644.2893635740775, 3644.58087319951, 3644.653757892996, 3646.268022689201, 4571.885702857455, 4863.294822250825, 4868.391451068616, 4905.694323667871, 4908.410990680998, 4697.483276839614, 4760.880977975578, 4773.451053128934, 4780.545848144421, 4790.760461976817, 4691.695039822401, 4695.0, 4733.715977041388, 4747.098051370229, 4775.33145359643, 4506.7070640408265, 4571.81712731487, 4695.333156729448, 4727.456342520654, 4758.31004504338, 4699.130660827758, 4747.905041899212, 4800.207965685623, 4811.903761924812, 4830.734309612864, 4726.598239149711, 4729.17239483096, 4735.674011276848, 4737.867959418207, 4772.880677983078], "value": 3947.5968584065336, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.4028655499549, 711.9466040046995, 734.6547122852257, 747.2154238050048, 797.4776521378499, 15530.223488825555, 16605.335786568554, 16693.499195024167, 16706.58233544162, 16712.58218544537, 11036.779264414707, 11246.557609744166, 14099.154050756963, 14152.50466233682, 14228.71542569148, 782.5930516131608, 797.6968751874309, 800.4356928365488, 802.743122200896, 803.6704950970103, 760.8021914302294, 763.3892885691453, 764.3808515102756, 770.3105720380256, 771.12862465414, 804.6982381606897, 807.3500831830374, 807.4347956430522, 807.5477732469807, 809.9190080991913, 800.0519384529351, 800.1718221640574, 800.1758189065272, 800.4676889868231, 804.6499772598909, 587.3187102960545, 589.7788329376484, 592.5970340168684, 593.4510577715596, 597.2981746447906, 15233.466828660943, 15242.923785381003, 15260.923695381563, 15267.38930442781, 15274.618134546614, 15221.31504082308, 15404.768928466083, 15502.767458488132, 15739.44911928077, 15942.681146377008, 11337.659870203906, 11414.201005929588, 11460.369679667614, 11671.0, 11727.648170554898, 3677.9448308275396, 3713.461548075541, 3757.3800322946954, 3897.922041559153, 3914.64768170866, 83065.29422645256, 83097.58451207705, 85778.0, 85794.14205857861, 86166.56916715376, 10416.32293900894, 10887.455627218607, 11254.831177532344, 11324.37715925632, 11350.432478376048, 20606.896965515076, 20619.175232990812, 20627.565073509268, 20789.79210207909, 20841.89579052095, 8243.8351232975, 8521.275691566287, 8637.611307491177, 8649.697260595975, 8820.11798820113, 4192.182524407772, 4427.889302767457, 4524.547545245515, 4659.0, 4938.9012219755405, 3471.409860323751, 3547.6984456320984, 3580.3197392495863, 3586.4620306954166, 3587.3722098632634, 538.2598926476095, 545.1958361416957, 547.2857920413898, 549.8295528386213, 570.6091327440716, 629.4555209743539, 671.2683175338807, 718.4683334332557, 732.4945787406672, 748.1620584944897, 8950.776230594158, 8961.59672814725, 9046.457212567268, 9059.456432614064, 9225.95387023074, 621.9689015549204, 647.6632151281308, 675.5338816216795, 695.1970474102319, 701.5930760159096, 4001.899952501211, 4374.073130907638, 4404.141192467502, 4434.911301774011, 4560.726356418625, 120055.73516869351, 123167.38416308041, 125253.0, 125264.0, 126219.3689031549, 64323.678381607795, 94898.0, 105122.88629081815, 106495.94784443254, 106984.46507767568, 77129.61435192788, 78349.0, 80961.59519202367, 86699.56650216835, 88737.0, 8711.782205444915, 8905.55472226399, 8946.910530894867, 8948.194662480408, 9236.168744813, 51074.48925510697, 52932.470675294295, 53046.0, 54260.609086370445, 56093.43906561045, 67545.66227168833, 68549.0, 76294.0, 77566.0, 78517.0, 12368.381580921097, 12593.433295501542, 12670.619881403572, 12896.161749486253, 13135.0, 13414.122022916605, 13576.117552359065, 13963.720725585228, 14034.29828508591, 14965.700685986438, 12262.325572093621, 12678.556250531186, 13046.282454465096, 13319.866801331862, 13484.0, 12745.49018039268, 12984.15602985803, 13171.07802453819, 13260.204387736767, 13582.456701732215, 12342.876571234532, 12346.135770496337, 12507.437165327765, 12785.232886026868, 13053.738925221633, 10229.693109206735, 10595.629152979609, 10704.143668506656, 10855.2401331906, 10907.29102608328, 10133.847992279974, 10330.690079297634, 10521.316114452842, 11582.6525204244, 11807.23252988553], "value": 7359.016659199234, "unit": "score"}], "name": "dromaeo_css", "value": 7018.007098310416, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 111.0, 109.0, 110.0, 111.0, 113.0, 112.0, 110.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 118.0, 119.0, 165.0, 124.0, 120.0, 118.0, 116.0, 153.0, 119.0], "value": 130.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 162.0, 161.0, 162.0, 160.0, 160.0, 161.0, 163.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 79.0, 110.0, 76.0, 77.0, 77.0, 76.0, 105.0, 75.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 121.0, 83.0, 83.0, 83.0, 116.0, 84.0, 83.0, 112.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 147.0, 147.0, 148.0, 147.0, 146.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 51.0, 50.0, 51.0, 51.0, 51.0, 51.0, 51.0, 52.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 61.0, 62.0, 61.0, 62.0, 61.0, 62.0, 62.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 78.0, 79.0, 84.0, 78.0, 86.0, 78.0, 77.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 125.0, 136.0, 125.0, 124.0, 125.0, 125.0, 124.0, 172.0, 147.0], "value": 133.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 169.0, 164.0, 165.0, 166.0, 166.0, 163.0, 162.0, 162.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 65.0, 66.0, 64.0, 65.0, 65.0, 64.0, 65.0, 63.0], "value": 65.1, "unit": "score"}], "name": "kraken", "value": 1492.1, "alertThreshold": 2.0}]} 02:47:47 INFO - Return code: 0 02:47:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:47:47 INFO - # TBPL SUCCESS # 02:47:47 INFO - Running post-action listener: _resource_record_post_action 02:47:47 INFO - [mozharness: 2016-09-21 09:47:47.866225Z] Finished run-tests step (success) 02:47:47 INFO - Running post-run listener: _resource_record_post_run 02:47:47 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 8192; Write bytes: 410050560; Read time: 52; Write time: 610720 02:47:47 INFO - TinderboxPrint: CPU usage
13.2% 02:47:47 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 52 02:47:47 INFO - TinderboxPrint: I/O write bytes / time
410,050,560 / 610,720 02:47:47 INFO - TinderboxPrint: CPU idle
8,301.7 (86.8%) 02:47:47 INFO - TinderboxPrint: CPU user
1,204.4 (12.6%) 02:47:47 INFO - TinderboxPrint: Swap in / out
0 / 0 02:47:47 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 120659968; Read time: 0; Write time: 210624 02:47:47 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 8192; Write bytes: 289390592; Read time: 52; Write time: 400096 02:47:48 INFO - Running post-run listener: _upload_blobber_files 02:47:48 INFO - Blob upload gear active. 02:47:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:47:48 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 02:47:48 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:47:48 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:47:48 INFO - (blobuploader) - INFO - Open directory for files ... 02:47:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:47:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:47:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:47:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:47:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:47:49 INFO - (blobuploader) - INFO - Done attempting. 02:47:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:47:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:47:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:47:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:47:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:47:50 INFO - (blobuploader) - INFO - Done attempting. 02:47:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:47:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:47:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:47:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:47:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:47:53 INFO - (blobuploader) - INFO - Done attempting. 02:47:53 INFO - (blobuploader) - INFO - Iteration through files over. 02:47:53 INFO - Return code: 0 02:47:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:47:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:47:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63913c05c026fe798d8d2f9286f6f9ed8e9fc79e05d35c07f1135b4caeb6459f881d3981ab51c3476df5844942405a3d34f28e662aa98173cee6f8a088cdfe31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3cc2b0430a9f39311550dc25aba08c3c1a091ec1dd51fa8e248e4f0beacc03fd8b0683492e77380de87c5a677b0e0c0e30d31f0167d8f395dac2b7c61e4113a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/124f305169320e00b287650437894835e101c781e7d1da79c89e8d5cdc9f548e6ffbf9134f16c1c8c05bc3e2b9af120906149cb06db3d680fc29ecace80803e2"} 02:47:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:47:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:47:53 INFO - Contents: 02:47:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63913c05c026fe798d8d2f9286f6f9ed8e9fc79e05d35c07f1135b4caeb6459f881d3981ab51c3476df5844942405a3d34f28e662aa98173cee6f8a088cdfe31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3cc2b0430a9f39311550dc25aba08c3c1a091ec1dd51fa8e248e4f0beacc03fd8b0683492e77380de87c5a677b0e0c0e30d31f0167d8f395dac2b7c61e4113a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/124f305169320e00b287650437894835e101c781e7d1da79c89e8d5cdc9f548e6ffbf9134f16c1c8c05bc3e2b9af120906149cb06db3d680fc29ecace80803e2"} 02:47:53 INFO - Running post-run listener: copy_logs_to_upload_dir 02:47:53 INFO - Copying logs to upload dir... 02:47:53 INFO - mkdir: /builds/slave/test/build/upload/logs 02:47:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.600841 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-09-21 02:47:53.689892) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 02:47:53.693426) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63913c05c026fe798d8d2f9286f6f9ed8e9fc79e05d35c07f1135b4caeb6459f881d3981ab51c3476df5844942405a3d34f28e662aa98173cee6f8a088cdfe31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3cc2b0430a9f39311550dc25aba08c3c1a091ec1dd51fa8e248e4f0beacc03fd8b0683492e77380de87c5a677b0e0c0e30d31f0167d8f395dac2b7c61e4113a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/124f305169320e00b287650437894835e101c781e7d1da79c89e8d5cdc9f548e6ffbf9134f16c1c8c05bc3e2b9af120906149cb06db3d680fc29ecace80803e2"} build_url:https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011396 build_url: 'https://queue.taskcluster.net/v1/task/b3pKvd8YS1WWWmyB8ADQKQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63913c05c026fe798d8d2f9286f6f9ed8e9fc79e05d35c07f1135b4caeb6459f881d3981ab51c3476df5844942405a3d34f28e662aa98173cee6f8a088cdfe31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3cc2b0430a9f39311550dc25aba08c3c1a091ec1dd51fa8e248e4f0beacc03fd8b0683492e77380de87c5a677b0e0c0e30d31f0167d8f395dac2b7c61e4113a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/124f305169320e00b287650437894835e101c781e7d1da79c89e8d5cdc9f548e6ffbf9134f16c1c8c05bc3e2b9af120906149cb06db3d680fc29ecace80803e2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 02:47:53.732519) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 02:47:53.732896) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474449636.471602-357942608 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004699 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 02:47:53.782622) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 02:47:53.782978) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 02:47:53.783290) ========= ========= Total master_lag: 0.25 =========