builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-022 starttime: 1474126388.15 results: success (0) buildid: 20160916143618 builduid: 6096345e4c47423bba4fe6e518696367 revision: f2fe5345c13ff5ec70dc259d653298a7cac61423 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 08:33:08.146005) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 08:33:08.146487) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 08:33:08.146818) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 08:33:08.161657) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:33:08.162043) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 08:33:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 66.8M=0s 2016-09-17 08:33:08 (66.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075648 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:33:08.253028) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:33:08.253864) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023693 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:33:08.303958) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:33:08.304351) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f2fe5345c13ff5ec70dc259d653298a7cac61423 --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 f2fe5345c13ff5ec70dc259d653298a7cac61423 --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=9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 08:33:08,754 truncating revision to first 12 chars 2016-09-17 08:33:08,754 Setting DEBUG logging. 2016-09-17 08:33:08,754 attempt 1/10 2016-09-17 08:33:08,754 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f2fe5345c13f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 08:33:09,082 unpacking tar archive at: fx-team-f2fe5345c13f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.632182 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:33:08.951018) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 08:33:08.951387) ========= script_repo_revision: f2fe5345c13ff5ec70dc259d653298a7cac61423 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 08:33:08.951905) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 08:33:08.952214) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 08:33:08.962414) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-09-17 08:33:08.962736) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:33:09 INFO - MultiFileLogger online at 20160917 08:33:09 in /builds/slave/test 08:33:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 08:33:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:33:09 INFO - {'append_to_log': False, 08:33:09 INFO - 'base_work_dir': '/builds/slave/test', 08:33:09 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 08:33:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:33:09 INFO - 'branch': 'Fx-Team-Non-PGO', 08:33:09 INFO - 'buildbot_json_path': 'buildprops.json', 08:33:09 INFO - 'config_files': ('talos/linux_config.py',), 08:33:09 INFO - 'default_actions': ('clobber', 08:33:09 INFO - 'read-buildbot-config', 08:33:09 INFO - 'download-and-extract', 08:33:09 INFO - 'populate-webroot', 08:33:09 INFO - 'create-virtualenv', 08:33:09 INFO - 'install', 08:33:09 INFO - 'run-tests'), 08:33:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:33:09 INFO - 'download_minidump_stackwalk': True, 08:33:09 INFO - 'download_symbols': 'ondemand', 08:33:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:33:09 INFO - 'tooltool.py': '/tools/tooltool.py', 08:33:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:33:09 INFO - '/tools/misc-python/virtualenv.py')}, 08:33:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:33:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:33:09 INFO - 'installer_path': 'installer.exe', 08:33:09 INFO - 'log_level': 'info', 08:33:09 INFO - 'log_name': 'talos', 08:33:09 INFO - 'log_to_console': True, 08:33:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:33:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:33:09 INFO - 'opt_config_files': (), 08:33:09 INFO - 'pip_index': False, 08:33:09 INFO - 'sps_profile': False, 08:33:09 INFO - 'sps_profile_interval': 0, 08:33:09 INFO - 'suite': 'dromaeojs', 08:33:09 INFO - 'system_bits': '32', 08:33:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:33:09 INFO - 'title': 'talos-linux64-ix-022', 08:33:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:33:09 INFO - 'use_talos_json': True, 08:33:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:33:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:33:09 INFO - 'work_dir': 'build'} 08:33:09 INFO - [mozharness: 2016-09-17 15:33:09.510854Z] Running clobber step. 08:33:09 INFO - Running pre-action listener: _resource_record_pre_action 08:33:09 INFO - Running main action method: clobber 08:33:09 INFO - rmtree: /builds/slave/test/build 08:33:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:33:09 INFO - Running post-action listener: _resource_record_post_action 08:33:09 INFO - [mozharness: 2016-09-17 15:33:09.968077Z] Finished clobber step (success) 08:33:09 INFO - [mozharness: 2016-09-17 15:33:09.968177Z] Running read-buildbot-config step. 08:33:09 INFO - Running pre-action listener: _resource_record_pre_action 08:33:09 INFO - Running main action method: read_buildbot_config 08:33:09 INFO - Using buildbot properties: 08:33:09 INFO - { 08:33:09 INFO - "project": "", 08:33:09 INFO - "product": "firefox", 08:33:09 INFO - "script_repo_revision": "production", 08:33:09 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:33:09 INFO - "repository": "", 08:33:09 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 08:33:09 INFO - "buildid": "20160916143618", 08:33:09 INFO - "pgo_build": "False", 08:33:09 INFO - "basedir": "/builds/slave/test", 08:33:09 INFO - "buildnumber": 1935, 08:33:09 INFO - "slavename": "talos-linux64-ix-022", 08:33:09 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:33:09 INFO - "platform": "ubuntu64_hw", 08:33:09 INFO - "scheduler": "tests-fx-team-linux64-talos", 08:33:09 INFO - "branch": "fx-team", 08:33:09 INFO - "repo_path": "integration/fx-team", 08:33:09 INFO - "revision": "f2fe5345c13ff5ec70dc259d653298a7cac61423", 08:33:09 INFO - "stage_platform": "linux64", 08:33:09 INFO - "builduid": "6096345e4c47423bba4fe6e518696367", 08:33:09 INFO - "slavebuilddir": "test" 08:33:09 INFO - } 08:33:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 08:33:09 INFO - Running post-action listener: _resource_record_post_action 08:33:09 INFO - [mozharness: 2016-09-17 15:33:09.970610Z] Finished read-buildbot-config step (success) 08:33:09 INFO - [mozharness: 2016-09-17 15:33:09.970699Z] Running download-and-extract step. 08:33:09 INFO - Running pre-action listener: _resource_record_pre_action 08:33:09 INFO - Running main action method: download_and_extract 08:33:09 INFO - mkdir: /builds/slave/test/build/tests 08:33:09 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')]} 08:33:09 INFO - trying https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:33:09 INFO - Downloading https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:33:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:33:10 INFO - Downloaded 1361 bytes. 08:33:10 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:33:10 INFO - Using the following test package requirements: 08:33:10 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:33:10 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:33:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:33:10 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:33:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:33:10 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:33:10 INFO - u'jsshell-linux-x86_64.zip'], 08:33:10 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:33:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:33:10 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:33:10 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:33:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:33:10 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:33:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:33:10 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:33:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:33:10 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:33:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:33:10 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:33:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:33:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:33:10 INFO - Fetch https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:33:11 INFO - Expected file size: 20365791 08:33:15 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:33:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:33:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:33:15 INFO - Fetch https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:33:15 INFO - Expected file size: 20365791 08:33:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 08:33:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:33:20 INFO - Fetch https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 08:33:21 INFO - Expected file size: 14414048 08:33:21 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')]} 08:33:21 INFO - trying https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:33:21 INFO - Downloading https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:33:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:33:22 INFO - Downloaded 55593420 bytes. 08:33:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:33:22 INFO - mkdir: /builds/slave/test/properties 08:33:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:33:22 INFO - Writing to file /builds/slave/test/properties/build_url 08:33:22 INFO - Contents: 08:33:22 INFO - build_url:https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:33:23 INFO - Running post-action listener: _resource_record_post_action 08:33:23 INFO - Running post-action listener: set_extra_try_arguments 08:33:23 INFO - [mozharness: 2016-09-17 15:33:23.252618Z] Finished download-and-extract step (success) 08:33:23 INFO - [mozharness: 2016-09-17 15:33:23.252845Z] Running populate-webroot step. 08:33:23 INFO - Running pre-action listener: _resource_record_pre_action 08:33:23 INFO - Running main action method: populate_webroot 08:33:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:33:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:33:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:33:23 INFO - '--tppagecycles', 08:33:23 INFO - '7'], 08:33:23 INFO - 'tests': ['tsvgm']}}, 08:33:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:33:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:33:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:33:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:33:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:33:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:33:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:23 INFO - 'talos_options': ['--disable-e10s'], 08:33:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:33:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:33:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:23 INFO - 'talos_options': ['--disable-e10s'], 08:33:23 INFO - 'tests': ['damp', 'tps']}, 08:33:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:23 INFO - 'tests': ['damp', 'tps']}, 08:33:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:33:23 INFO - 'tests': ['dromaeo_dom']}, 08:33:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:33:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:33:23 INFO - 'tests': ['basic_compositor_video']}, 08:33:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:33:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:33:23 INFO - 'tests': ['a11yr', 08:33:23 INFO - 'ts_paint', 08:33:23 INFO - 'tpaint', 08:33:23 INFO - 'sessionrestore', 08:33:23 INFO - 'sessionrestore_no_auto_restore', 08:33:23 INFO - 'tabpaint']}, 08:33:23 INFO - 'other-e10s': {'tests': ['a11yr', 08:33:23 INFO - 'ts_paint', 08:33:23 INFO - 'tpaint', 08:33:23 INFO - 'sessionrestore', 08:33:23 INFO - 'sessionrestore_no_auto_restore', 08:33:23 INFO - 'tabpaint']}, 08:33:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:33:23 INFO - 'ts_paint', 08:33:23 INFO - 'tpaint', 08:33:23 INFO - 'sessionrestore', 08:33:23 INFO - 'sessionrestore_no_auto_restore', 08:33:23 INFO - 'tabpaint']}, 08:33:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:33:23 INFO - 'ts_paint', 08:33:23 INFO - 'tpaint', 08:33:23 INFO - 'sessionrestore', 08:33:23 INFO - 'sessionrestore_no_auto_restore', 08:33:23 INFO - 'tabpaint']}, 08:33:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:33:23 INFO - 'tests': ['a11yr', 08:33:23 INFO - 'ts_paint', 08:33:23 INFO - 'tpaint', 08:33:23 INFO - 'sessionrestore', 08:33:23 INFO - 'sessionrestore_no_auto_restore', 08:33:23 INFO - 'tabpaint']}, 08:33:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:33:23 INFO - 'tests': ['a11yr', 08:33:23 INFO - 'ts_paint', 08:33:23 INFO - 'tpaint', 08:33:23 INFO - 'sessionrestore', 08:33:23 INFO - 'sessionrestore_no_auto_restore', 08:33:23 INFO - 'tabpaint']}, 08:33:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:33:23 INFO - 'tests': ['tsvgx', 08:33:23 INFO - 'tsvgr_opacity', 08:33:23 INFO - 'tart', 08:33:23 INFO - 'tscrollx', 08:33:23 INFO - 'cart']}, 08:33:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:33:23 INFO - 'tsvgr_opacity', 08:33:23 INFO - 'tart', 08:33:23 INFO - 'tscrollx', 08:33:23 INFO - 'cart']}, 08:33:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:23 INFO - 'talos_options': ['--disable-e10s'], 08:33:23 INFO - 'tests': ['tp5o']}, 08:33:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:23 INFO - 'tests': ['tp5o']}, 08:33:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:23 INFO - 'talos_options': ['--disable-e10s', 08:33:23 INFO - '--xperf_path', 08:33:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:33:23 INFO - 'tests': ['tp5n']}, 08:33:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:23 INFO - 'talos_options': ['--xperf_path', 08:33:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:33:23 INFO - 'tests': ['tp5n']}}, 08:33:23 INFO - 'talos.zip': {'path': '', 08:33:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:33:23 INFO - Running post-action listener: _resource_record_post_action 08:33:23 INFO - [mozharness: 2016-09-17 15:33:23.271676Z] Finished populate-webroot step (success) 08:33:23 INFO - [mozharness: 2016-09-17 15:33:23.271764Z] Running create-virtualenv step. 08:33:23 INFO - Running pre-action listener: _resource_record_pre_action 08:33:23 INFO - Running main action method: create_virtualenv 08:33:23 INFO - Creating virtualenv /builds/slave/test/build/venv 08:33:23 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 08:33:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:33:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:33:23 INFO - Using real prefix '/usr' 08:33:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:33:23 INFO - Installing distribute.............................................................................................................................................................................................done. 08:33:25 INFO - Installing pip.................done. 08:33:25 INFO - Return code: 0 08:33:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:33:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:25 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 0x7fca5ec11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2665ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662f60>, '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 0x2663d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26641d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', '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 08:33:25 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 08:33:25 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 08:33:25 INFO - Using env: {'DISPLAY': ':0', 08:33:25 INFO - 'HOME': '/home/cltbld', 08:33:25 INFO - 'LANG': 'en_US.UTF-8', 08:33:25 INFO - 'LANGUAGE': 'en_US:en', 08:33:25 INFO - 'LOGNAME': 'cltbld', 08:33:25 INFO - 'MAIL': '/var/mail/cltbld', 08:33:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:25 INFO - 'MOZ_NO_REMOTE': '1', 08:33:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:25 INFO - 'NO_EM_RESTART': '1', 08:33:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:25 INFO - 'PWD': '/builds/slave/test', 08:33:25 INFO - 'SHELL': '/bin/bash', 08:33:25 INFO - 'SHLVL': '1', 08:33:25 INFO - 'TERM': 'linux', 08:33:25 INFO - 'TMOUT': '86400', 08:33:25 INFO - 'USER': 'cltbld', 08:33:25 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', 08:33:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:25 INFO - '_': '/tools/buildbot/bin/python'} 08:33:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:26 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 08:33:26 INFO - Cleaning up... 08:33:26 INFO - Return code: 0 08:33:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:33:26 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')]} 08:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:26 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')]} 08:33:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:26 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 0x7fca5ec11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2665ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662f60>, '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 0x2663d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26641d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', '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 08:33:26 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 08:33:26 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 08:33:26 INFO - Using env: {'DISPLAY': ':0', 08:33:26 INFO - 'HOME': '/home/cltbld', 08:33:26 INFO - 'LANG': 'en_US.UTF-8', 08:33:26 INFO - 'LANGUAGE': 'en_US:en', 08:33:26 INFO - 'LOGNAME': 'cltbld', 08:33:26 INFO - 'MAIL': '/var/mail/cltbld', 08:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:26 INFO - 'MOZ_NO_REMOTE': '1', 08:33:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:26 INFO - 'NO_EM_RESTART': '1', 08:33:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:26 INFO - 'PWD': '/builds/slave/test', 08:33:26 INFO - 'SHELL': '/bin/bash', 08:33:26 INFO - 'SHLVL': '1', 08:33:26 INFO - 'TERM': 'linux', 08:33:26 INFO - 'TMOUT': '86400', 08:33:26 INFO - 'USER': 'cltbld', 08:33:26 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', 08:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:26 INFO - '_': '/tools/buildbot/bin/python'} 08:33:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:26 INFO - Downloading/unpacking psutil>=3.1.1 08:33:26 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 08:33:26 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 08:33:26 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 08:33:26 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 08:33:26 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 08:33:26 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 08:33:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:33:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:33:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:33:28 INFO - Installing collected packages: psutil 08:33:28 INFO - Running setup.py install for psutil 08:33:28 INFO - building 'psutil._psutil_linux' extension 08:33:28 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 08:33:28 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 08:33:28 INFO - building 'psutil._psutil_posix' extension 08:33:28 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 08:33:28 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 08:33:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:33:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:33:28 INFO - Successfully installed psutil 08:33:28 INFO - Cleaning up... 08:33:28 INFO - Return code: 0 08:33:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:33: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')]} 08:33:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33: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')]} 08:33:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca5ec11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2665ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662f60>, '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 0x2663d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26641d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', '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 08:33: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:33: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 mozsystemmonitor==0.3 08:33:28 INFO - Using env: {'DISPLAY': ':0', 08:33:28 INFO - 'HOME': '/home/cltbld', 08:33:28 INFO - 'LANG': 'en_US.UTF-8', 08:33:28 INFO - 'LANGUAGE': 'en_US:en', 08:33:28 INFO - 'LOGNAME': 'cltbld', 08:33:28 INFO - 'MAIL': '/var/mail/cltbld', 08:33:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:28 INFO - 'MOZ_NO_REMOTE': '1', 08:33:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:28 INFO - 'NO_EM_RESTART': '1', 08:33:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:28 INFO - 'PWD': '/builds/slave/test', 08:33:28 INFO - 'SHELL': '/bin/bash', 08:33:28 INFO - 'SHLVL': '1', 08:33:28 INFO - 'TERM': 'linux', 08:33:28 INFO - 'TMOUT': '86400', 08:33:28 INFO - 'USER': 'cltbld', 08:33:28 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', 08:33:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:28 INFO - '_': '/tools/buildbot/bin/python'} 08:33:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:33:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:33:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:33:31 INFO - Installing collected packages: mozsystemmonitor 08:33:31 INFO - Running setup.py install for mozsystemmonitor 08:33:31 INFO - Successfully installed mozsystemmonitor 08:33:31 INFO - Cleaning up... 08:33:31 INFO - Return code: 0 08:33:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:33: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')]} 08:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33: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')]} 08:33:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33: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', '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 0x7fca5ec11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2665ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662f60>, '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 0x2663d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26641d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', '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 08:33: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:33: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 jsonschema==2.5.1 08:33:31 INFO - Using env: {'DISPLAY': ':0', 08:33:31 INFO - 'HOME': '/home/cltbld', 08:33:31 INFO - 'LANG': 'en_US.UTF-8', 08:33:31 INFO - 'LANGUAGE': 'en_US:en', 08:33:31 INFO - 'LOGNAME': 'cltbld', 08:33:31 INFO - 'MAIL': '/var/mail/cltbld', 08:33:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:31 INFO - 'MOZ_NO_REMOTE': '1', 08:33:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:31 INFO - 'NO_EM_RESTART': '1', 08:33:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:31 INFO - 'PWD': '/builds/slave/test', 08:33:31 INFO - 'SHELL': '/bin/bash', 08:33:31 INFO - 'SHLVL': '1', 08:33:31 INFO - 'TERM': 'linux', 08:33:31 INFO - 'TMOUT': '86400', 08:33:31 INFO - 'USER': 'cltbld', 08:33:31 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', 08:33:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:31 INFO - '_': '/tools/buildbot/bin/python'} 08:33:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:31 INFO - Downloading/unpacking jsonschema==2.5.1 08:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:33:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:33 INFO - Downloading functools32-3.2.3-2.tar.gz 08:33:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:33:33 INFO - warning: no files found matching '*.txt' 08:33:33 INFO - no previously-included directories found matching 'build' 08:33:33 INFO - no previously-included directories found matching 'dist' 08:33:33 INFO - no previously-included directories found matching '.git*' 08:33:33 INFO - Installing collected packages: jsonschema, functools32 08:33:33 INFO - Running setup.py install for functools32 08:33:33 INFO - warning: no files found matching '*.txt' 08:33:33 INFO - no previously-included directories found matching 'build' 08:33:33 INFO - no previously-included directories found matching 'dist' 08:33:33 INFO - no previously-included directories found matching '.git*' 08:33:33 INFO - Successfully installed jsonschema functools32 08:33:33 INFO - Cleaning up... 08:33:33 INFO - Return code: 0 08:33:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:33:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fca5ec11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2665ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662f60>, '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 0x2663d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26641d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', '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 08:33:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:33:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:33:33 INFO - Using env: {'DISPLAY': ':0', 08:33:33 INFO - 'HOME': '/home/cltbld', 08:33:33 INFO - 'LANG': 'en_US.UTF-8', 08:33:33 INFO - 'LANGUAGE': 'en_US:en', 08:33:33 INFO - 'LOGNAME': 'cltbld', 08:33:33 INFO - 'MAIL': '/var/mail/cltbld', 08:33:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:33 INFO - 'MOZ_NO_REMOTE': '1', 08:33:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:33 INFO - 'NO_EM_RESTART': '1', 08:33:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:33 INFO - 'PWD': '/builds/slave/test', 08:33:33 INFO - 'SHELL': '/bin/bash', 08:33:33 INFO - 'SHLVL': '1', 08:33:33 INFO - 'TERM': 'linux', 08:33:33 INFO - 'TMOUT': '86400', 08:33:33 INFO - 'USER': 'cltbld', 08:33:33 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', 08:33:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:33 INFO - '_': '/tools/buildbot/bin/python'} 08:33:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:33:33 INFO - Cleaning up... 08:33:33 INFO - Return code: 0 08:33:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:33:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fca5ec11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2665ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662f60>, '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 0x2663d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26641d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', '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 08:33:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:33:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:33:33 INFO - Using env: {'DISPLAY': ':0', 08:33:33 INFO - 'HOME': '/home/cltbld', 08:33:33 INFO - 'LANG': 'en_US.UTF-8', 08:33:33 INFO - 'LANGUAGE': 'en_US:en', 08:33:33 INFO - 'LOGNAME': 'cltbld', 08:33:33 INFO - 'MAIL': '/var/mail/cltbld', 08:33:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:33 INFO - 'MOZ_NO_REMOTE': '1', 08:33:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:33 INFO - 'NO_EM_RESTART': '1', 08:33:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:33 INFO - 'PWD': '/builds/slave/test', 08:33:33 INFO - 'SHELL': '/bin/bash', 08:33:33 INFO - 'SHLVL': '1', 08:33:33 INFO - 'TERM': 'linux', 08:33:33 INFO - 'TMOUT': '86400', 08:33:33 INFO - 'USER': 'cltbld', 08:33:33 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', 08:33:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:33 INFO - '_': '/tools/buildbot/bin/python'} 08:33:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:34 INFO - Downloading/unpacking blobuploader==1.2.4 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33:36 INFO - Downloading blobuploader-1.2.4.tar.gz 08:33:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:33:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:33:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33:36 INFO - Downloading docopt-0.6.1.tar.gz 08:33:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:33:36 INFO - Installing collected packages: blobuploader, requests, docopt 08:33:36 INFO - Running setup.py install for blobuploader 08:33:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:33:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:33:36 INFO - Running setup.py install for requests 08:33:37 INFO - Running setup.py install for docopt 08:33:37 INFO - Successfully installed blobuploader requests docopt 08:33:37 INFO - Cleaning up... 08:33:37 INFO - Return code: 0 08:33:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:33: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')]} 08:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33: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')]} 08:33:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:37 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 0x7fca5ec11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2665ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662f60>, '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 0x2663d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26641d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', '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 08:33:37 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 08:33:37 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 08:33:37 INFO - Using env: {'DISPLAY': ':0', 08:33:37 INFO - 'HOME': '/home/cltbld', 08:33:37 INFO - 'LANG': 'en_US.UTF-8', 08:33:37 INFO - 'LANGUAGE': 'en_US:en', 08:33:37 INFO - 'LOGNAME': 'cltbld', 08:33:37 INFO - 'MAIL': '/var/mail/cltbld', 08:33:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:37 INFO - 'MOZ_NO_REMOTE': '1', 08:33:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:37 INFO - 'NO_EM_RESTART': '1', 08:33:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:37 INFO - 'PWD': '/builds/slave/test', 08:33:37 INFO - 'SHELL': '/bin/bash', 08:33:37 INFO - 'SHLVL': '1', 08:33:37 INFO - 'TERM': 'linux', 08:33:37 INFO - 'TMOUT': '86400', 08:33:37 INFO - 'USER': 'cltbld', 08:33:37 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', 08:33:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:37 INFO - '_': '/tools/buildbot/bin/python'} 08:33:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:33:37 INFO - Running setup.py (path:/tmp/pip-guox3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:33:37 INFO - Running setup.py (path:/tmp/pip-zOfnee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:33:37 INFO - Running setup.py (path:/tmp/pip-xgtOGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:33:37 INFO - Running setup.py (path:/tmp/pip-lhO6hW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:33:37 INFO - Running setup.py (path:/tmp/pip-sdbgSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:33:37 INFO - Running setup.py (path:/tmp/pip-UrGo1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:33:38 INFO - Running setup.py (path:/tmp/pip-SlAWgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:33:38 INFO - Running setup.py (path:/tmp/pip-mPTkmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:33:38 INFO - Running setup.py (path:/tmp/pip-bCiaAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:33:38 INFO - Running setup.py (path:/tmp/pip-tnu8dT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:33:38 INFO - Running setup.py (path:/tmp/pip-DPIpcl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:33:38 INFO - Running setup.py (path:/tmp/pip-MrRfAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:33:38 INFO - Running setup.py (path:/tmp/pip-zRJsso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:33:38 INFO - Running setup.py (path:/tmp/pip-2cfxaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:33:38 INFO - Running setup.py (path:/tmp/pip-PHP819-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:33:38 INFO - Running setup.py (path:/tmp/pip-VMq_3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:33:38 INFO - Running setup.py (path:/tmp/pip-KmqyDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:33:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:33:38 INFO - Running setup.py install for manifestparser 08:33:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:33:38 INFO - Running setup.py install for mozcrash 08:33:39 INFO - Running setup.py install for mozdebug 08:33:39 INFO - Running setup.py install for mozdevice 08:33:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:33:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:33:39 INFO - Running setup.py install for mozfile 08:33:39 INFO - Running setup.py install for mozhttpd 08:33:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:33:39 INFO - Running setup.py install for mozinfo 08:33:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:33:39 INFO - Running setup.py install for mozInstall 08:33:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:33:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:33:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:33:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:33:39 INFO - Running setup.py install for mozleak 08:33:39 INFO - Running setup.py install for mozlog 08:33:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:33:39 INFO - Running setup.py install for moznetwork 08:33:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:33:39 INFO - Running setup.py install for mozprocess 08:33:40 INFO - Running setup.py install for mozprofile 08:33:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:33:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:33:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:33:40 INFO - Running setup.py install for mozrunner 08:33:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:33:40 INFO - Running setup.py install for mozscreenshot 08:33:40 INFO - Running setup.py install for moztest 08:33:40 INFO - Running setup.py install for mozversion 08:33:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:33:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:33:40 INFO - Cleaning up... 08:33:40 INFO - Return code: 0 08:33:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:33: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')]} 08:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33: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')]} 08:33:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:40 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 0x7fca5ec11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2665ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662f60>, '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 0x2663d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26641d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', '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 08:33:40 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 08:33:40 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 08:33:40 INFO - Using env: {'DISPLAY': ':0', 08:33:40 INFO - 'HOME': '/home/cltbld', 08:33:40 INFO - 'LANG': 'en_US.UTF-8', 08:33:40 INFO - 'LANGUAGE': 'en_US:en', 08:33:40 INFO - 'LOGNAME': 'cltbld', 08:33:40 INFO - 'MAIL': '/var/mail/cltbld', 08:33:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:40 INFO - 'MOZ_NO_REMOTE': '1', 08:33:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:40 INFO - 'NO_EM_RESTART': '1', 08:33:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:40 INFO - 'PWD': '/builds/slave/test', 08:33:40 INFO - 'SHELL': '/bin/bash', 08:33:40 INFO - 'SHLVL': '1', 08:33:40 INFO - 'TERM': 'linux', 08:33:40 INFO - 'TMOUT': '86400', 08:33:40 INFO - 'USER': 'cltbld', 08:33:40 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', 08:33:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:40 INFO - '_': '/tools/buildbot/bin/python'} 08:33:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:33:40 INFO - Running setup.py (path:/tmp/pip-12RKtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:33:40 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)) 08:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:33:40 INFO - Running setup.py (path:/tmp/pip-mAaGM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:33:41 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)) 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:33:41 INFO - Running setup.py (path:/tmp/pip-uh81Hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:33:41 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)) 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:33:41 INFO - Running setup.py (path:/tmp/pip-ZmKcKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:33:41 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)) 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:33:41 INFO - Running setup.py (path:/tmp/pip-1w47Y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:33:41 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)) 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:33:41 INFO - Running setup.py (path:/tmp/pip-2UPGUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:33:41 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)) 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:33:41 INFO - Running setup.py (path:/tmp/pip-6Q1Qdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:33:41 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)) 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:33:41 INFO - Running setup.py (path:/tmp/pip-lSoeur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:33:41 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)) 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:33:41 INFO - Running setup.py (path:/tmp/pip-mYdKke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:33:41 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)) 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:33:41 INFO - Running setup.py (path:/tmp/pip-AlC8Yz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:33:41 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)) 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:33:41 INFO - Running setup.py (path:/tmp/pip-CoGpwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:33:41 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)) 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:33:41 INFO - Running setup.py (path:/tmp/pip-SEGk9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:33:41 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)) 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:33:41 INFO - Running setup.py (path:/tmp/pip-RO5MQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:33:41 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)) 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:33:41 INFO - Running setup.py (path:/tmp/pip-LS_W7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:33:41 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)) 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:33:41 INFO - Running setup.py (path:/tmp/pip-WDi0m8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:33:41 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)) 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:33:41 INFO - Running setup.py (path:/tmp/pip-JbM2lt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:33:42 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)) 08:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:33:42 INFO - Running setup.py (path:/tmp/pip-Zdf2Ir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:33:42 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)) 08:33:42 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)) 08:33:42 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)) 08:33:42 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)) 08:33:42 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)) 08:33:42 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)) 08:33:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:43 INFO - Downloading blessings-1.6.tar.gz 08:33:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:33:44 INFO - Installing collected packages: blessings 08:33:44 INFO - Running setup.py install for blessings 08:33:44 INFO - Successfully installed blessings 08:33:44 INFO - Cleaning up... 08:33:44 INFO - Return code: 0 08:33:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:33:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:33:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:33:44 INFO - Reading from file tmpfile_stdout 08:33:44 INFO - Current package versions: 08:33:44 INFO - argparse == 1.2.1 08:33:44 INFO - blessings == 1.6 08:33:44 INFO - blobuploader == 1.2.4 08:33:44 INFO - docopt == 0.6.1 08:33:44 INFO - functools32 == 3.2.3-2 08:33:44 INFO - jsonschema == 2.5.1 08:33:44 INFO - manifestparser == 1.1 08:33:44 INFO - mozInstall == 1.12 08:33:44 INFO - mozcrash == 0.17 08:33:44 INFO - mozdebug == 0.1 08:33:44 INFO - mozdevice == 0.48 08:33:44 INFO - mozfile == 1.2 08:33:44 INFO - mozhttpd == 0.7 08:33:44 INFO - mozinfo == 0.9 08:33:44 INFO - mozleak == 0.1 08:33:44 INFO - mozlog == 3.4 08:33:44 INFO - moznetwork == 0.27 08:33:44 INFO - mozprocess == 0.23 08:33:44 INFO - mozprofile == 0.28 08:33:44 INFO - mozrunner == 6.12 08:33:44 INFO - mozscreenshot == 0.1 08:33:44 INFO - mozsystemmonitor == 0.3 08:33:44 INFO - moztest == 0.7 08:33:44 INFO - mozversion == 1.4 08:33:44 INFO - psutil == 3.1.1 08:33:44 INFO - requests == 1.2.3 08:33:44 INFO - wsgiref == 0.1.2 08:33:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:44 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 0x7fca5ec11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2665ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662f60>, '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 0x2663d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26641d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', '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 08:33:44 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 08:33:44 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 08:33:44 INFO - Using env: {'DISPLAY': ':0', 08:33:44 INFO - 'HOME': '/home/cltbld', 08:33:44 INFO - 'LANG': 'en_US.UTF-8', 08:33:44 INFO - 'LANGUAGE': 'en_US:en', 08:33:44 INFO - 'LOGNAME': 'cltbld', 08:33:44 INFO - 'MAIL': '/var/mail/cltbld', 08:33:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:44 INFO - 'MOZ_NO_REMOTE': '1', 08:33:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:44 INFO - 'NO_EM_RESTART': '1', 08:33:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:44 INFO - 'PWD': '/builds/slave/test', 08:33:44 INFO - 'SHELL': '/bin/bash', 08:33:44 INFO - 'SHLVL': '1', 08:33:44 INFO - 'TERM': 'linux', 08:33:44 INFO - 'TMOUT': '86400', 08:33:44 INFO - 'USER': 'cltbld', 08:33:44 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', 08:33:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:44 INFO - '_': '/tools/buildbot/bin/python'} 08:33:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:44 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)) 08:33:44 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)) 08:33:44 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)) 08:33:44 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)) 08:33:44 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)) 08:33:44 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)) 08:33:44 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)) 08:33:44 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)) 08:33:44 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)) 08:33:44 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)) 08:33:44 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)) 08:33:44 INFO - Cleaning up... 08:33:44 INFO - Return code: 0 08:33:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca5ec11f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2665ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2662f60>, '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 0x2663d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26641d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', '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 08:33:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:33:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:33:44 INFO - Using env: {'DISPLAY': ':0', 08:33:44 INFO - 'HOME': '/home/cltbld', 08:33:44 INFO - 'LANG': 'en_US.UTF-8', 08:33:44 INFO - 'LANGUAGE': 'en_US:en', 08:33:44 INFO - 'LOGNAME': 'cltbld', 08:33:44 INFO - 'MAIL': '/var/mail/cltbld', 08:33:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:44 INFO - 'MOZ_NO_REMOTE': '1', 08:33:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:33:44 INFO - 'NO_EM_RESTART': '1', 08:33:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:33:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:44 INFO - 'PWD': '/builds/slave/test', 08:33:44 INFO - 'SHELL': '/bin/bash', 08:33:44 INFO - 'SHLVL': '1', 08:33:44 INFO - 'TERM': 'linux', 08:33:44 INFO - 'TMOUT': '86400', 08:33:44 INFO - 'USER': 'cltbld', 08:33:44 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', 08:33:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:44 INFO - '_': '/tools/buildbot/bin/python'} 08:33:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:33:44 INFO - Cleaning up... 08:33:44 INFO - Return code: 0 08:33:44 INFO - Running post-action listener: _resource_record_post_action 08:33:44 INFO - Running post-action listener: _start_resource_monitoring 08:33:44 INFO - Starting resource monitoring. 08:33:44 INFO - [mozharness: 2016-09-17 15:33:44.940916Z] Finished create-virtualenv step (success) 08:33:44 INFO - [mozharness: 2016-09-17 15:33:44.941534Z] Running install step. 08:33:44 INFO - Running pre-action listener: _resource_record_pre_action 08:33:44 INFO - Running main action method: install 08:33:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:33:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:33:45 INFO - Reading from file tmpfile_stdout 08:33:45 INFO - Detecting whether we're running mozinstall >=1.0... 08:33:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:33:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:33:45 INFO - Reading from file tmpfile_stdout 08:33:45 INFO - Output received: 08:33:45 INFO - Usage: mozinstall [options] installer 08:33:45 INFO - Options: 08:33:45 INFO - -h, --help show this help message and exit 08:33:45 INFO - -d DEST, --destination=DEST 08:33:45 INFO - Directory to install application into. [default: 08:33:45 INFO - "/builds/slave/test"] 08:33:45 INFO - --app=APP Application being installed. [default: firefox] 08:33:45 INFO - mkdir: /builds/slave/test/build/application 08:33:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:33:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:34:01 INFO - Reading from file tmpfile_stdout 08:34:01 INFO - Output received: 08:34:01 INFO - /builds/slave/test/build/application/firefox/firefox 08:34:01 INFO - Running post-action listener: _resource_record_post_action 08:34:01 INFO - [mozharness: 2016-09-17 15:34:01.344460Z] Finished install step (success) 08:34:01 INFO - [mozharness: 2016-09-17 15:34:01.344703Z] Running run-tests step. 08:34:01 INFO - Running pre-action listener: _resource_record_pre_action 08:34:01 INFO - Running main action method: run_tests 08:34:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:34:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:34:01 INFO - Python 2.7.3 08:34:01 INFO - Return code: 0 08:34:01 INFO - grabbing minidump binary from tooltool 08:34:01 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')]} 08:34:01 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 0x2662f60>, '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 0x2663d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26641d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:34:01 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 08:34:01 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 08:34:01 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 08:34:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:34:01 INFO - Return code: 0 08:34:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:34:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:34:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:34:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:34:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:34:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 08:34:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --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 08:34:01 INFO - Using env: {'DISPLAY': ':0', 08:34:01 INFO - 'HOME': '/home/cltbld', 08:34:01 INFO - 'LANG': 'en_US.UTF-8', 08:34:01 INFO - 'LANGUAGE': 'en_US:en', 08:34:01 INFO - 'LOGNAME': 'cltbld', 08:34:01 INFO - 'MAIL': '/var/mail/cltbld', 08:34:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:34:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:01 INFO - 'MOZ_NO_REMOTE': '1', 08:34:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:34:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:34:01 INFO - 'NO_EM_RESTART': '1', 08:34:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:01 INFO - 'PWD': '/builds/slave/test', 08:34:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:34:01 INFO - 'SHELL': '/bin/bash', 08:34:01 INFO - 'SHLVL': '1', 08:34:01 INFO - 'TERM': 'linux', 08:34:01 INFO - 'TMOUT': '86400', 08:34:01 INFO - 'USER': 'cltbld', 08:34:01 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454', 08:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:01 INFO - '_': '/tools/buildbot/bin/python'} 08:34:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 08:34:01 INFO - mozversion application_buildid: 20160916143618 08:34:01 INFO - mozversion application_changeset: f2fe5345c13ff5ec70dc259d653298a7cac61423 08:34:01 INFO - mozversion application_display_name: Nightly 08:34:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:34:01 INFO - mozversion application_name: Firefox 08:34:01 INFO - mozversion application_remotingname: firefox 08:34:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:34:01 INFO - mozversion application_vendor: Mozilla 08:34:01 INFO - mozversion application_version: 51.0a1 08:34:01 INFO - mozversion platform_buildid: 20160916143618 08:34:01 INFO - mozversion platform_changeset: f2fe5345c13ff5ec70dc259d653298a7cac61423 08:34:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:34:01 INFO - mozversion platform_version: 51.0a1 08:34:01 INFO - using testdate: 1474126441 08:34:01 INFO - actual date: 1474126441 08:34:01 INFO - starting webserver on 'localhost:44324' 08:34:01 INFO - SUITE-START | Running 2 tests 08:34:01 INFO - TEST-START | dromaeo_css 08:34:01 INFO - operating with platform_type : linux_ 08:34:01 INFO - Initialising browser for dromaeo_css test... 08:34:01 INFO - TEST-INFO | started process 9507 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgYUqoe/profile http://localhost:44324/getInfo.html) 08:34:06 INFO - PROCESS | 9507 | __metrics Screen width/height:1600/1200 08:34:06 INFO - PROCESS | 9507 | colorDepth:24 08:34:06 INFO - PROCESS | 9507 | Browser inner width/height: 1024/697 08:34:06 INFO - PROCESS | 9507 | __metrics 08:34:06 INFO - PROCESS | 9507 | 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" 08:34:06 INFO - PROCESS | 9507 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:34:07 INFO - PROCESS | 9507 | console.error: PushService: 08:34:07 INFO - PROCESS | 9507 | stateChangeProcessEnqueue: Error transitioning state 08:34:07 INFO - PROCESS | 9507 | UnknownError 08:34:08 INFO - TEST-INFO | 9507: exit 0 08:34:08 INFO - Browser initialized. 08:34:08 INFO - Running cycle 1/1 for dromaeo_css test... 08:34:08 INFO - TEST-INFO | started process 9588 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgYUqoe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:34:20 INFO - PROCESS | 9588 | RSS: Main: 174354432 08:34:20 INFO - PROCESS | 9588 | 08:34:55 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 08:34:57 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.03333 seconds. 08:35:01 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:35:05 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.03343 seconds. 08:35:07 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:35:11 INFO - PROCESS | 9588 | 1474126511835 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] 08:35:20 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:35:22 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:35:26 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:35:27 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 2.05008 seconds. 08:35:32 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.03347 seconds. 08:35:34 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 08:35:55 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.06672 seconds. 08:35:58 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 08:36:00 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 08:36:16 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.06672 seconds. 08:36:20 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:36:29 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 08:36:49 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 08:36:57 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 08:36:59 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:37:11 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.80007 seconds. 08:37:15 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 2.01675 seconds. 08:37:15 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 2.01675 seconds. 08:37:19 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.16664 seconds. 08:37:24 INFO - PROCESS | 9588 | Cycle 1(1): loaded http://localhost:44324/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44324/tests/dromaeo/cssquery-ext.html) 08:37:25 INFO - PROCESS | 9588 | RSS: Main: 291110912 08:37:25 INFO - PROCESS | 9588 | 08:37:29 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 08:37:40 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:37:42 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:37:48 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:38:12 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:38:31 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:38:39 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:38:43 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:38:45 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:38:47 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 08:38:49 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:39:07 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:39:11 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:39:13 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:39:25 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:39:30 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:39:32 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:39:39 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:39:41 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:39:42 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 2.01676 seconds. 08:39:47 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:39:52 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 08:39:54 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:39:55 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 2.01675 seconds. 08:39:57 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:40:10 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 08:40:16 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:40:18 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:40:22 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:40:28 INFO - PROCESS | 9588 | Cycle 1(1): loaded http://localhost:44324/tests/dromaeo/cssquery-ext.html (next: http://localhost:44324/tests/dromaeo/cssquery-jquery.html) 08:40:29 INFO - PROCESS | 9588 | RSS: Main: 275726336 08:40:29 INFO - PROCESS | 9588 | 08:40:33 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:40:34 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 2.03341 seconds. 08:40:39 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:40:49 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 08:40:55 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:41:07 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:41:21 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:41:24 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:41:28 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:41:40 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:41:42 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:41:52 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:42:00 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:42:08 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:42:10 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:42:13 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:42:14 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:42:15 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 2.01676 seconds. 08:42:18 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:42:34 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:42:36 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:42:38 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:42:40 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:42:50 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:42:52 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 08:43:05 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:43:07 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:43:09 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:43:11 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:43:23 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:43:31 INFO - PROCESS | 9588 | Cycle 1(1): loaded http://localhost:44324/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44324/tests/dromaeo/cssquery-mootools.html) 08:43:31 INFO - PROCESS | 9588 | RSS: Main: 250732544 08:43:31 INFO - PROCESS | 9588 | 08:43:35 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:43:36 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:43:50 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:44:05 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:44:06 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:44:08 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:44:37 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:44:51 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:44:58 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:45:01 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:45:03 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:45:05 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:45:17 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:45:21 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:45:27 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 08:45:37 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:45:39 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:46:15 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:46:22 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:46:27 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:46:30 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:46:32 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:46:34 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:46:34 INFO - PROCESS | 9588 | Cycle 1(1): loaded http://localhost:44324/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44324/tests/dromaeo/cssquery-prototype.html) 08:46:34 INFO - PROCESS | 9588 | RSS: Main: 220037120 08:46:34 INFO - PROCESS | 9588 | 08:46:38 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 08:46:43 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:47:02 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.03343 seconds. 08:47:05 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:47:16 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:47:19 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:47:21 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:48:15 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.03333 seconds. 08:48:22 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:48:24 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 2.00007 seconds. 08:48:38 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:48:39 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:48:40 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 2.01674 seconds. 08:48:49 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:48:51 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:48:53 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:48:57 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:49:10 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:49:14 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:49:20 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:49:25 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:49:27 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:49:37 INFO - PROCESS | 9588 | Cycle 1(1): loaded http://localhost:44324/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44324/tests/dromaeo/cssquery-yui.html) 08:49:38 INFO - PROCESS | 9588 | RSS: Main: 219021312 08:49:38 INFO - PROCESS | 9588 | 08:49:41 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:49:48 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 08:49:54 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:49:59 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00273 seconds. 08:50:03 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:50:09 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:50:11 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:50:17 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:50:33 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:50:39 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:50:49 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01663 seconds. 08:50:57 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 08:51:24 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:51:30 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 08:51:38 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:51:42 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:51:49 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:51:52 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:52:31 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 08:52:39 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:52:41 INFO - PROCESS | 9588 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 08:52:41 INFO - PROCESS | 9588 | Cycle 1(1): loaded http://localhost:44324/tests/dromaeo/cssquery-yui.html (next: http://localhost:44324/tests/dromaeo/cssquery-dojo.html) 08:52:41 INFO - PROCESS | 9588 | RSS: Main: 300060672 08:52:41 INFO - PROCESS | 9588 | 08:52:41 INFO - PROCESS | 9588 | __start_tp_report 08:52:41 INFO - PROCESS | 9588 | _x_x_mozilla_page_load 08:52:41 INFO - PROCESS | 9588 | _x_x_mozilla_page_load_details 08:52:41 INFO - PROCESS | 9588 | |i|pagename|runs| 08:52:41 INFO - PROCESS | 9588 | |0;dojo.html;2542.1610868413427;2612.2293923292627;2647;2656.8140230183894;2667.413169102798;4525.660575456845;4594.931076033862;4682.321063445798;4701.7649117544115;4706.62347012239;4615.030843522856;4665.253559430492;4701.50634183411;4717.481077081518;4734.763261836912;4347.7173983690955;4348.695591308609;4365.672574556905;4379.321205213181;4446.688731788776;4349.630281426083;4379.0585024219945;4389.385486031958;4389.9561004390025;4430.512643609217;4245.8513952011845;4291.0774183550375;4295.312749959991;4346.8913277168;4423;4320.351947207912;4324.978375108136;4336.089421221547;4340.978295108536;4341.978290108561;4284.228838809013;4304.203722311351;4305.246581848164;4305.827766889321;4309.525952145262;4577.244754615484;4602.585767280927;4623.352730617717;4689.9765501172615;4696.835610753641;4931.4821943696115;4934.4572097069295;4941.011797640486;5003.249512573107;5003.874903127415;4962.60299176065;4964.05682920244;4972.87567810804;4976.805566664011;5034.823781167642;4563;4567.269236922077;4616.422947131608;4623.861284161447;4695.178343789823;5058.721770302669;5118.744062796845;5129.410117836496;5140.203268493389;5193.532582067632;4864.318995340655;4898.485659005753;4899.412070551557;4905.730184839868;4969.453360130383;4982.078315511642;4995.725235112103;4998.475160108136;5001;5063.240513922941;4919.434265059493;4971.353724015855;4971.7016978980655;5031.169856973632;5034.395872495324;4957.975210123998;4989.12690279207;5014.949850501448;5066.189409694432;5068.746562671851;4213.9578604214175;4219.683523735732;4221.6200541951375;4227;4274.358846173037;3602.171500554887;3707.870224542182;3711.6473934976134;3736.308782875177;3757.3800322946954;4307.784610769511;4340.131973605292;4366.563343665608;4366.803493842721;4375.6280716138845;5030.597552195815;5034.244863270539;5092.898142037213;5095.261187127846;5098.413682426494;4316.460442444694;4326.069894972596;4358.585934336233;4365;4372.453443319585;4332.328489084197;4370.759608221515;4418.734875907391;4451.554844515523;4510.864674059783;4774.069056533943;4838.153323168361;4854.004928989478;4912.066707325596;4914.434839993446;5137.2037334213255;5144.434112247724;5147.613928955268;5148.43367229612;5193;4857.906970931512;4865.635077369141;4865.659403841697;4868.440129385166;4915.4592994771265;4872.9269060964825;4902.093112774148;4934.062528119789;4960.23116416956;5114.028334616411;5073.568746656576;5074.873128171826;5075.822346217865;5087.669301495391;5135.717535535581;4925.088858561177;4947.3568436104515;4947.60419166473;4950.430700469492;4951.356323677899;4695.248760198352;4793.065352256416;4854.660173787801;4945.035718034948;5006.148954677641;4810.542998415145;4816.710997340171;4840.95159048405;4873.610111191026;4900.14247506692;4929.16204245272;4929.679570827885;4939.382577177853;4944.431390390151;4958.900821983612;4770.0936822003705;4827.927581086356;4831.444383895897;4836.056968891033;4905.043516514245 08:52:41 INFO - PROCESS | 9588 | |1;ext.html;544.9318835145607;553.9584531160164;574.1387918122814;577.751566826265;585.0668184246126;28808.27979300516;30700.232494187658;30710.84644576777;31111.68888311116;39062.21875562494;40027.79986100073;40696.761678107694;40706;40804.59195408045;40866;7526.473146879721;7660.348870346;7675.347595454393;7678.155402905676;7678.385729141655;7218.133823941134;7401.925980740178;7547.094348678166;9727.610895564168;10017.799644007115;10175.84736228957;10181.6945491635;10229.130523905476;10238.846417303746;10259.538320775546;10057.748556286115;10099.646512372105;10114.393136411838;10118.038786315361;10122.89877101227;9785.706428807147;9850.113489785883;9856.802863942681;9859.30984831062;9865.654702085392;38537.61462385368;38814.22371552581;38863;38866.417003744966;38907.61092389068;2692.0846912049997;2828.2081017315186;3234.951475727866;3254.1539199807985;3329.51722000311;28874.422511549863;28956.27609309763;29067.12798616045;29158.562621560694;29382.706172938426;3193.0899693587216;3214.7428205743718;3226.177324782189;3230.07942736319;3352.4468462703626;248445;249253;249478;249515;249548;27974.3006424841;53403.73298133562;53941.46058539443;53950.73024634852;54345;30245.69754302429;32216;32283.51574726381;32330.191745206557;32347;11539.249948753304;11726.179167458367;11731.23746956445;11735.941320293345;11738.237014594024;8595.140485951355;9476.905230947741;9578.664746733832;9607.567659455202;9619.807603848023;7236.131664200329;7579.62101894903;7809.882851757228;7813.531188128731;7861.606919654108;3068.3556453144643;3073.9855847570248;3098.178982569621;3102.0848849589424;3110.144710204712;753.2091304130641;774.2605811450052;833.6832003838502;839.412411312084;871.9738407847648;27629.447411051668;27874.442511150068;27987.321396633255;27997.44005119886;28213.29466763306;903.6746771162376;928.8885333759993;940.6002448959165;972.5186032913753;1007.6725064354114;13847.515336963157;14774.556763297118;14845.703085938221;14920.179390133648;15107.093574385572;374693;378327;379570;380944;381213;49787.25319120216;50288;50315.49684503181;50371.74814125979;50396.24405633919;1024.0680980307884;1051.4795176387738;1091.9617813376494;1105.6075093341908;1166.8949794518535;28045;29591.40817183601;29653;29915.850420747756;30472.542911856784;41548.58451415447;42292.154156917306;42393.576064238965;42395.1520969585;42450.575494245895;302528;306107.8167654974;306391;570123;597354;35489.82255088708;36133.63866361303;36510.63489365072;36525.08687282839;36904.630953691194;40441;40749.185016298914;40782.59217407788;41219.38170927498;41359.1728165429;34661.826690866386;35222.11944701403;35387.115322117155;35574.11064723299;35673.82163089168;34691.826540867136;34875;34957.65042349646;35007.64992350044;35073.47389789104;34096;34482.137946551535;35143.12142196363;35278.82360588181;35671.46492802558;29375.265618359714;31463.685363146076;31848.681513184572;32106.51840222352;32146;21811.563768724853;21834.235801747498;22132.66800997954;22256;22274.220402285842 08:52:41 INFO - PROCESS | 9588 | |2;jquery.html;310.51094526121364;330.24374183120653;332.3137720606945;336.8719886443152;347.3972657439342;10735.892641073588;15495;16055.919720401382;16164.030158190502;17339.826601733977;15256.389744410239;15533.766993495079;16413.261403236855;17341.306347746075;17559;3994.600539945997;4859.975700121495;4890.529443034069;5062.240663900426;5064.113780088488;4862.051899879525;4965.031818795336;4972.527609877074;4973.254011898208;4979.875503112415;4735.455422626391;4938.555530002299;4938.580220681229;4942.703437793726;4942.851714448554;4813.446453657822;4887.95112048882;4888.633352498577;4894.559489645913;4898.559129678329;4330.653547716207;4332.111917057027;4333.414988976511;4337.240982828024;4338.154059958278;19716.50708732293;20223.898880505505;20245.8987705062;20339.593208135902;20637.58724825495;1643.8191798902112;1774.4562230859235;1856.5265857206462;1886.311496303853;1972.0829814136353;8604.569771511462;8639.136086391374;8641.783955401103;8836.911630883673;12019.63941081769;1680.2103011584536;1745.2757105801077;1753.7106377447706;1763.867709921761;1784.3308759215295;66483.66758166226;68090.31909680889;69305;69446;69831.65084174597;9538.570764315622;9551.376266034791;9723.173530250007;9772.267079969031;9958.15355694774;13149.934250328688;13166.670833229246;13184.27486488233;13191.868081319257;13209.40557674907;6515.381038801306;6732.091167692397;6852.554583952027;6876;6919.861602767916;6270.216222972129;6325.683715814191;6332.620042797447;6492.707828147744;6501.382368674968;4105.8973525662095;4113.794310284474;4158.792060397029;4229.577042295808;4247.086876321606;1687.1985806741945;1687.4009726547145;1729.8962062276303;1753.2022929567017;1760.5510594798245;715.5349023135004;762.2301475509774;767.1906139023384;782.8003859016028;785.4619585583894;15469.226538673247;15686.372545098067;15745;15789.684206316038;16343.427980020642;765.0640518741156;766.8734658781239;797.4218691448654;799.564237490569;826.9173082691683;8584.399092063495;8593.398462107718;8951.686690965786;8957.596908139149;9115.635374584943;368817;377942;381497;382511;383921;48823.755881220364;50289;74799;80232.79650805243;85931;853.7054716122927;882.9514376709215;885.2652298592153;886.2806156057228;886.822635472908;13912;14065.507707230197;14102.36539355711;14354.641133971734;14841;35676;36698.44952325771;38047.239055219296;39955.60044399519;41876.790616046725;51906;53627.73186134044;54213.45786542242;54535.72732136314;55066.72466637641;6591.242007169045;6940.757073502403;7166.641667916479;7169.3906017986965;7272.200057993511;9742.805143896941;10880.836787448354;11082.833757493483;11509.884901150881;11771.411429428666;9491;9623.711288661352;10002.899971000197;10014.198864091;10195.949020255148;9263.814723705624;9657.130858222512;10059.899401005896;10060.748481288027;10122.595096196364;9918.504074796376;10238.18094552449;10429.843552346872;10483.68548943533;10650.57397704083;7024.192217895007;7153.535020223669;7173.246809084971;7185.82035449097;7307.086614173229;9308.348415610842;9975.251856110675;10212.234082443698;10334.5349459273;10536.051755342056 08:52:41 INFO - PROCESS | 9588 | |3;mootools.html;1208.7884620191464;1267.961961141166;1299.610116964911;1339.5110784563633;1622.716024695678;5896.321922978858;7193.928060719391;7466.589337586431;7486.513376630515;7596.240375962401;7461.4403919705965;7534.3972482201425;7534.547927124377;7535.924640753591;7536.869469579566;2028.381343690174;2050.425880753384;2053.7638171610306;2055.465578949469;2056.1775289884017;2003.8597298189134;2052.1791283486577;2054.7328847249837;2055.239561362298;2055.9074841632164;2021.2723419568943;2022.4842665120448;2030.624334498115;2033.4611327998093;2034.9389518314474;1972.8224459798614;1974.8815071095637;1975.8221760041665;1978.8317992970615;1981.435290942089;1968.0651690447066;1979.574391505833;2012.5471768852037;2027.168860767093;2028.2354351713955;7157.248488908694;7443;7458.291462311126;7561.697532098709;7563.432742544332;4130.669546436308;4287.056847493546;4290.2706539888295;4295.226859165349;4300.182965236595;2687.3281679580105;2786.9303267418272;2838.8722507487214;2839.162447078117;2853.9857300713365;865.4547634989979;866.7356456280831;879.6349514951288;891.7592250092504;907.3829795738895;9959.352642078315;10102.444365559892;10104.595816167344;10105.595776168944;10111.19110471168;3599.262151258986;3657.5428071491065;3754.568224654145;3790.128270497801;3790.75360101598;7996.120426753053;8012.238837310446;8018.238267364591;8029.397795165386;8033.678652853937;4154.5222299435345;4154.626083652425;4157.417961485395;4159.313713237282;4160.105577300895;3348.8325583720716;3470.097774578577;3728.683061939711;3877.747946383476;3880.670143037817;3188.091393952712;3192.3615276944706;3289.802611843249;3306.5866766654167;3326.534285200074;978.8287049766262;988.1452543549777;1006.6527048168371;1008.2740426892625;1016.5425558498705;622.8038167977073;713.8465229975476;728.3626826526789;738.9963050184822;757.4092208077708;4604.58558729716;4618.861434156981;4667.789949452283;4689.390379250675;4810.95189048112;374.10402087002024;376.70616918803375;381.7270651484202;383.54549858417306;385.5354298070818;3185.315157241158;3252.9674703252663;3262.918427039343;3266.379387916336;3290.851911663981;8875.378723489293;9291.070892910655;9300.8604870927;9311.487868167316;9320.766980825534;9212.263018958467;9223;9229.123233292827;9238.030006849318;9249.075092490697;6990.335918087773;7057.047298614724;7060.7881763547175;7067.187273463617;7098.39663628587;4760.476347601831;4805.783739731651;4837.733924634038;4907.312976183266;5039.798408063636;8498.490090594583;8560.57197140153;8564.828703426021;8567.785805354915;8590.098039705741;8445.577721114043;8478.660853566034;8664.046954835088;8682.739517814476;8710.604583739268;6074.210352654127;6141.140240366263;6153.907691384722;6157.630542167484;6158.414950579688;6111.633302001894;6410.647414392253;6690.6320152390135;6743.325667433218;6872.553284036521;5882.705864706833;5948.4943779779205;5976.372480889618;5991.191189189403;6142.262928448615;5831.79588714393;6116.113163591343;6139.416755408229;6152.292486363946;6233.407826256498;5769.336526299361;5779.508741756998;5789.1605717171615;5822.184894114743;5866.794662186803;4155.750654960712;4254.361845723166;4261.488621365332;4291.721038132511;4389.056352884145;4099.405586190045;4200.789960502024;4204.726692764961;4262.637675797592;4460.286354183445 08:52:41 INFO - PROCESS | 9588 | |4;prototype.html;276.6486562066176;277.0662866141105;283.63977748259725;284.44107329098324;285.48897473522385;4706.576408123268;4739.194336962716;4796.872734907294;4802.97598512007;4811.446683631379;4729.787159577827;4746.264329028997;4756.5243475652505;4757.666963312561;4767.690100143497;3522.7886326820344;3630.3283892479976;3640.3265395901713;3758.5677647070524;3763.1909139534996;3560.6439356064448;3632.9636703633023;3641.1443310821937;3641.1794102948525;3715.9442608360896;3657.1771351445846;3683.5395575553057;3684.2631473705233;3686.705063594906;3698.5931547529885;3704.518412606371;3704.592494825567;3707.4068149096015;3708.462272970418;3708.5178926739623;3189.9202519936957;3189.9362012759616;3198.088544764731;3238.886638967648;3274.705276525124;4655.511171326996;4663.137319595851;4664.370310008139;4667.27310894971;4671.11248862715;4207.684423668206;4217.8312867485265;4221.472315960505;4231.376546541133;4232.894177645553;4672.859814205579;4939.851804445872;4942.654014219006;4946.703197808143;4948.604111671094;3457.1529975155995;3468.150303175713;3470.9132271693156;3471.6354782747953;3471.7743346682387;5208.817691380784;5223.3209682741;5237.607179461517;5243.685378877241;5246.160614301693;3531.1172206948263;3534.823258837048;3537.911552211164;3538.115471132217;3568.1614820517157;3509.9298014039578;3560.8219589020446;3560.8219589020446;3577;3578.7137029037613;4078.898027549276;4087.346024636044;4095.97952010244;4103.281925662997;4105.67154627629;4937.950620493749;4939.87650308738;4947.688862451453;4954.950450495449;4964;1205.4756181061266;1223.4127618743053;1237.826704261404;1240.9503619855286;1243.6890777305673;768.404486522938;785.6464590933988;788.3299195683737;790.9564973926489;794.8807678848219;3954.406838974177;3977.880663580097;3983.780892050965;4261.849300688797;4372.322290044985;5024.221245706921;5143;5146.845594632167;5156.432792392834;5166.656669266018;4483.50681425043;4487.596116349544;4492.326151077365;4494.70784399013;4502.302143167814;2660.773834224074;2680.249530131566;2714.5792402177694;2714.9185524434297;2744.67063952327;4887.804487820518;4894.363732714724;4895.510448955147;4907.901841963141;4915.926261106087;5261.789528418897;5264.710440925785;5265.709901074185;5274.736263186825;5277.815276465382;4634.513376095529;4835.927461088017;4843.4430040545785;4893.314935908976;4895.681780684244;5111.769970351269;5117.974410127926;5120.283160357479;5125.974370128126;5129.204973229155;5028.974855125847;5032.169692000779;5033.219850923113;5039.5968322534845;5040.269160971712;4688.921548043901;4792.376990991149;4847.854564363075;4867.221244600847;4873.634477414137;3519.3665140273974;3533.469979503095;3535.1692352297705;3540.415831387792;3541.8229088544954;5092.796288148433;5093.71984540839;5102.974485127551;5121.436641969306;5127.79488820443;4811.975940120277;4898.608111351155;4957.578605818546;4974.900501990012;4980.8754781130765;4965.925511117408;4967.60259179272;4969.1800852860315;4969.776360063878;4972.3038774572315;4864.829730959399;4873.537013983666;4882.2432522959;4887.633427492881;4892.339534162985;4763.7141771493825;4925.945913533632;4969.204927211629;4970.229614409773;4993.600511959107;4633.606143477842;4767.809287628456;4786.396557153393;4893.535114164149;4900.779464924016 08:52:41 INFO - PROCESS | 9588 | |5;yui.html;564.5483613109512;598.3597550620832;598.6527813867955;617.65411369633;629.8330942300291;12233.449494772734;12851;13134.408951597177;13194.736105277889;13198.076134670577;12323.69190770229;12590.118691691585;12601.11792174546;12606.558770443036;12611.43248553815;592.2508027345411;724.5399171526066;739.3973911262331;741.3735393592433;743.1305372713938;603.0291231117897;610.8014895159091;611.0071134406589;717.2934659360526;745.2845268542206;783.5416281475344;788.2314743125436;788.530824159627;789.0373744032271;789.0570767932323;789.0886026639183;789.2699253190775;789.3448437796615;789.4394979564516;789.5065584009993;452.7555120235069;458.63309352518183;462.1081313065815;575.4360726488065;575.7812031428073;9753.317267791257;11160.553577856876;11642.301461912313;11711.297322160697;11917.582884599084;13031.739365212738;13502.662433439242;13782;14062.507812226626;14077.507287245;9907.653232136912;9984.35101718386;10004.299699021072;10009.099181073665;10041.096301332842;3121.7801379091684;3139.7645176611845;3157.8263195524237;3164.240582260264;3238.17426556229;64778;66340.6682966587;66877.3312266876;67330.3266967329;67568.66215668939;10023.498825058718;10052.748681283027;10149.64476243328;10348.689539313833;10526.263161578769;17280.913595431943;17304.480865574053;17304.65390692204;17311.740323895152;17315.480535583953;6683.2314283857;6727.62998035103;6751.864962700817;6967.79096627102;6967.895481567679;3591.4074177760376;3595.658412450813;3623.5108260385036;3653.926921461556;3653.963460365362;2671.6927553331225;2770.1135636596096;2775.9867648308223;2779.3329600896045;2781.4993301205977;440.3131115459893;448.74421579699913;453.301915050818;456.17432447270545;461.62377662205193;448.13957202171906;484.48886424821444;498.9226669866176;525.1912055434588;559.5998860814537;7690.384769218449;7767.650455729506;8042;8051;8208.220219079178;468.67427138139345;469.73694730950774;510.58897587441726;540.7999040355094;550.8182299841043;3686.060054686038;3705.629437056327;3747.2318174774105;3810.8475660973804;3954.7033972451604;59317;59414.40585594089;60767.696161519794;80457;102115;67559;68041.65979170073;69017;80140.5992970043;81641.18358816455;44806.77596611996;62112.68943655343;62258;64300.678496607215;70548.2945170552;7824.452288339761;8273.751787446387;8330.291925186428;8418.284445822173;8844.601992910462;46107;47480;47584.2862357058;48238;48274.517254827006;62706;65426.784455476656;65957.34042659512;66555.66722166358;69792;11165;11254.155938304824;11350.143568766256;11816.468258928197;12392.938035309766;12011.339376334385;12011.63965081049;12118.697032574257;12900.935495322463;13037.15258508194;11654.825177622512;11857.703557411134;12329.691757706129;12507.812382814447;12804.167729097579;10741.24811263204;10819.18856085781;11046.834297485384;11358.943205284251;11691.941540292244;11995.64013079609;12107.273563586214;12685.302308372758;12881.549145779853;12886.613401597968;9854.605815767289;9947.900520994697;9980.650677226262;10193.694189174337;10553.947230263799;9238.260939124988;9746.80506389854;9845.064718851698;9903.5305818001;9908.108270255712 08:52:41 INFO - PROCESS | 9588 | __end_tp_report 08:52:41 INFO - PROCESS | 9588 | __start_cc_report 08:52:41 INFO - PROCESS | 9588 | _x_x_mozilla_cycle_collect,1053 08:52:41 INFO - PROCESS | 9588 | __end_cc_report 08:52:41 INFO - PROCESS | 9588 | __startTimestamp1474127561669__endTimestamp 08:52:41 INFO - PROCESS | 9588 | 08:52:41 INFO - PROCESS | 9588 | ------- Summary: start ------- 08:52:41 INFO - PROCESS | 9588 | Number of tests: 6 08:52:41 INFO - PROCESS | 9588 | 08:52:41 INFO - PROCESS | 9588 | [#0] dojo.html Cycles:165 Average:4648.48 Median:4831.44 stddev:491.63 (10.2%) stddev-sans-first:464.54 08:52:41 INFO - PROCESS | 9588 | Values: 2542.2 2612.2 2647.0 2656.8 2667.4 4525.7 4594.9 4682.3 4701.8 4706.6 4615.0 4665.3 4701.5 4717.5 4734.8 4347.7 4348.7 4365.7 4379.3 4446.7 4349.6 4379.1 4389.4 4390.0 4430.5 4245.9 4291.1 4295.3 4346.9 4423.0 4320.4 4325.0 4336.1 4341.0 4342.0 4284.2 4304.2 4305.2 4305.8 4309.5 4577.2 4602.6 4623.4 4690.0 4696.8 4931.5 4934.5 4941.0 5003.2 5003.9 4962.6 4964.1 4972.9 4976.8 5034.8 4563.0 4567.3 4616.4 4623.9 4695.2 5058.7 5118.7 5129.4 5140.2 5193.5 4864.3 4898.5 4899.4 4905.7 4969.5 4982.1 4995.7 4998.5 5001.0 5063.2 4919.4 4971.4 4971.7 5031.2 5034.4 4958.0 4989.1 5014.9 5066.2 5068.7 4214.0 4219.7 4221.6 4227.0 4274.4 3602.2 3707.9 3711.6 3736.3 3757.4 4307.8 4340.1 4366.6 4366.8 4375.6 5030.6 5034.2 5092.9 5095.3 5098.4 4316.5 4326.1 4358.6 4365.0 4372.5 4332.3 4370.8 4418.7 4451.6 4510.9 4774.1 4838.2 4854.0 4912.1 4914.4 5137.2 5144.4 5147.6 5148.4 5193.0 4857.9 4865.6 4865.7 4868.4 4915.5 4872.9 4902.1 4934.1 4960.2 5114.0 5073.6 5074.9 5075.8 5087.7 5135.7 4925.1 4947.4 4947.6 4950.4 4951.4 4695.2 4793.1 4854.7 4945.0 5006.1 4810.5 4816.7 4841.0 4873.6 4900.1 4929.2 4929.7 4939.4 4944.4 4958.9 4770.1 4827.9 4831.4 4836.1 4905.0 08:52:41 INFO - PROCESS | 9588 | 08:52:41 INFO - PROCESS | 9588 | [#1] ext.html Cycles:180 Average:48724.61 Median:34982.65 stddev:96890.23 (277.0%) stddev-sans-first:97094.50 08:52:41 INFO - PROCESS | 9588 | Values: 544.9 554.0 574.1 577.8 585.1 28808.3 30700.2 30710.8 31111.7 39062.2 40027.8 40696.8 40706.0 40804.6 40866.0 7526.5 7660.3 7675.3 7678.2 7678.4 7218.1 7401.9 7547.1 9727.6 10017.8 10175.8 10181.7 10229.1 10238.8 10259.5 10057.7 10099.6 10114.4 10118.0 10122.9 9785.7 9850.1 9856.8 9859.3 9865.7 38537.6 38814.2 38863.0 38866.4 38907.6 2692.1 2828.2 3235.0 3254.2 3329.5 28874.4 28956.3 29067.1 29158.6 29382.7 3193.1 3214.7 3226.2 3230.1 3352.4 248445.0 249253.0 249478.0 249515.0 249548.0 27974.3 53403.7 53941.5 53950.7 54345.0 30245.7 32216.0 32283.5 32330.2 32347.0 11539.2 11726.2 11731.2 11735.9 11738.2 8595.1 9476.9 9578.7 9607.6 9619.8 7236.1 7579.6 7809.9 7813.5 7861.6 3068.4 3074.0 3098.2 3102.1 3110.1 753.2 774.3 833.7 839.4 872.0 27629.4 27874.4 27987.3 27997.4 28213.3 903.7 928.9 940.6 972.5 1007.7 13847.5 14774.6 14845.7 14920.2 15107.1 374693.0 378327.0 379570.0 380944.0 381213.0 49787.3 50288.0 50315.5 50371.7 50396.2 1024.1 1051.5 1092.0 1105.6 1166.9 28045.0 29591.4 29653.0 29915.9 30472.5 41548.6 42292.2 42393.6 42395.2 42450.6 302528.0 306107.8 306391.0 570123.0 597354.0 35489.8 36133.6 36510.6 36525.1 36904.6 40441.0 40749.2 40782.6 41219.4 41359.2 34661.8 35222.1 35387.1 35574.1 35673.8 34691.8 34875.0 34957.7 35007.6 35073.5 34096.0 34482.1 35143.1 35278.8 35671.5 29375.3 31463.7 31848.7 32106.5 32146.0 21811.6 21834.2 22132.7 22256.0 22274.2 08:52:41 INFO - PROCESS | 9588 | 08:52:41 INFO - PROCESS | 9588 | [#2] jquery.html Cycles:180 Average:23483.24 Median:4836.71 stddev:62622.66 (1294.7%) stddev-sans-first:62774.16 08:52:41 INFO - PROCESS | 9588 | Values: 310.5 330.2 332.3 336.9 347.4 10735.9 15495.0 16055.9 16164.0 17339.8 15256.4 15533.8 16413.3 17341.3 17559.0 3994.6 4860.0 4890.5 5062.2 5064.1 4862.1 4965.0 4972.5 4973.3 4979.9 4735.5 4938.6 4938.6 4942.7 4942.9 4813.4 4888.0 4888.6 4894.6 4898.6 4330.7 4332.1 4333.4 4337.2 4338.2 19716.5 20223.9 20245.9 20339.6 20637.6 1643.8 1774.5 1856.5 1886.3 1972.1 8604.6 8639.1 8641.8 8836.9 12019.6 1680.2 1745.3 1753.7 1763.9 1784.3 66483.7 68090.3 69305.0 69446.0 69831.7 9538.6 9551.4 9723.2 9772.3 9958.2 13149.9 13166.7 13184.3 13191.9 13209.4 6515.4 6732.1 6852.6 6876.0 6919.9 6270.2 6325.7 6332.6 6492.7 6501.4 4105.9 4113.8 4158.8 4229.6 4247.1 1687.2 1687.4 1729.9 1753.2 1760.6 715.5 762.2 767.2 782.8 785.5 15469.2 15686.4 15745.0 15789.7 16343.4 765.1 766.9 797.4 799.6 826.9 8584.4 8593.4 8951.7 8957.6 9115.6 368817.0 377942.0 381497.0 382511.0 383921.0 48823.8 50289.0 74799.0 80232.8 85931.0 853.7 883.0 885.3 886.3 886.8 13912.0 14065.5 14102.4 14354.6 14841.0 35676.0 36698.4 38047.2 39955.6 41876.8 51906.0 53627.7 54213.5 54535.7 55066.7 6591.2 6940.8 7166.6 7169.4 7272.2 9742.8 10880.8 11082.8 11509.9 11771.4 9491.0 9623.7 10002.9 10014.2 10195.9 9263.8 9657.1 10059.9 10060.7 10122.6 9918.5 10238.2 10429.8 10483.7 10650.6 7024.2 7153.5 7173.2 7185.8 7307.1 9308.3 9975.3 10212.2 10334.5 10536.1 08:52:41 INFO - PROCESS | 9588 | 08:52:41 INFO - PROCESS | 9588 | [#3] mootools.html Cycles:180 Average:4750.78 Median:4724.93 stddev:2743.62 (58.1%) stddev-sans-first:2738.40 08:52:41 INFO - PROCESS | 9588 | Values: 1208.8 1268.0 1299.6 1339.5 1622.7 5896.3 7193.9 7466.6 7486.5 7596.2 7461.4 7534.4 7534.5 7535.9 7536.9 2028.4 2050.4 2053.8 2055.5 2056.2 2003.9 2052.2 2054.7 2055.2 2055.9 2021.3 2022.5 2030.6 2033.5 2034.9 1972.8 1974.9 1975.8 1978.8 1981.4 1968.1 1979.6 2012.5 2027.2 2028.2 7157.2 7443.0 7458.3 7561.7 7563.4 4130.7 4287.1 4290.3 4295.2 4300.2 2687.3 2786.9 2838.9 2839.2 2854.0 865.5 866.7 879.6 891.8 907.4 9959.4 10102.4 10104.6 10105.6 10111.2 3599.3 3657.5 3754.6 3790.1 3790.8 7996.1 8012.2 8018.2 8029.4 8033.7 4154.5 4154.6 4157.4 4159.3 4160.1 3348.8 3470.1 3728.7 3877.7 3880.7 3188.1 3192.4 3289.8 3306.6 3326.5 978.8 988.1 1006.7 1008.3 1016.5 622.8 713.8 728.4 739.0 757.4 4604.6 4618.9 4667.8 4689.4 4811.0 374.1 376.7 381.7 383.5 385.5 3185.3 3253.0 3262.9 3266.4 3290.9 8875.4 9291.1 9300.9 9311.5 9320.8 9212.3 9223.0 9229.1 9238.0 9249.1 6990.3 7057.0 7060.8 7067.2 7098.4 4760.5 4805.8 4837.7 4907.3 5039.8 8498.5 8560.6 8564.8 8567.8 8590.1 8445.6 8478.7 8664.0 8682.7 8710.6 6074.2 6141.1 6153.9 6157.6 6158.4 6111.6 6410.6 6690.6 6743.3 6872.6 5882.7 5948.5 5976.4 5991.2 6142.3 5831.8 6116.1 6139.4 6152.3 6233.4 5769.3 5779.5 5789.2 5822.2 5866.8 4155.8 4254.4 4261.5 4291.7 4389.1 4099.4 4200.8 4204.7 4262.6 4460.3 08:52:41 INFO - PROCESS | 9588 | 08:52:41 INFO - PROCESS | 9588 | [#4] prototype.html Cycles:180 Average:4107.69 Median:4734.49 stddev:1215.64 (25.7%) stddev-sans-first:1184.55 08:52:41 INFO - PROCESS | 9588 | Values: 276.6 277.1 283.6 284.4 285.5 4706.6 4739.2 4796.9 4803.0 4811.4 4729.8 4746.3 4756.5 4757.7 4767.7 3522.8 3630.3 3640.3 3758.6 3763.2 3560.6 3633.0 3641.1 3641.2 3715.9 3657.2 3683.5 3684.3 3686.7 3698.6 3704.5 3704.6 3707.4 3708.5 3708.5 3189.9 3189.9 3198.1 3238.9 3274.7 4655.5 4663.1 4664.4 4667.3 4671.1 4207.7 4217.8 4221.5 4231.4 4232.9 4672.9 4939.9 4942.7 4946.7 4948.6 3457.2 3468.2 3470.9 3471.6 3471.8 5208.8 5223.3 5237.6 5243.7 5246.2 3531.1 3534.8 3537.9 3538.1 3568.2 3509.9 3560.8 3560.8 3577.0 3578.7 4078.9 4087.3 4096.0 4103.3 4105.7 4938.0 4939.9 4947.7 4955.0 4964.0 1205.5 1223.4 1237.8 1241.0 1243.7 768.4 785.6 788.3 791.0 794.9 3954.4 3977.9 3983.8 4261.8 4372.3 5024.2 5143.0 5146.8 5156.4 5166.7 4483.5 4487.6 4492.3 4494.7 4502.3 2660.8 2680.2 2714.6 2714.9 2744.7 4887.8 4894.4 4895.5 4907.9 4915.9 5261.8 5264.7 5265.7 5274.7 5277.8 4634.5 4835.9 4843.4 4893.3 4895.7 5111.8 5118.0 5120.3 5126.0 5129.2 5029.0 5032.2 5033.2 5039.6 5040.3 4688.9 4792.4 4847.9 4867.2 4873.6 3519.4 3533.5 3535.2 3540.4 3541.8 5092.8 5093.7 5103.0 5121.4 5127.8 4812.0 4898.6 4957.6 4974.9 4980.9 4965.9 4967.6 4969.2 4969.8 4972.3 4864.8 4873.5 4882.2 4887.6 4892.3 4763.7 4925.9 4969.2 4970.2 4993.6 4633.6 4767.8 4786.4 4893.5 4900.8 08:52:41 INFO - PROCESS | 9588 | 08:52:41 INFO - PROCESS | 9588 | [#5] yui.html Cycles:180 Average:16563.34 Median:465.39 stddev:22538.41 (4842.9%) stddev-sans-first:22569.62 08:52:41 INFO - PROCESS | 9588 | Values: 564.5 598.4 598.7 617.7 629.8 12233.4 12851.0 13134.4 13194.7 13198.1 12323.7 12590.1 12601.1 12606.6 12611.4 592.3 724.5 739.4 741.4 743.1 603.0 610.8 611.0 717.3 745.3 783.5 788.2 788.5 789.0 789.1 789.1 789.3 789.3 789.4 789.5 452.8 458.6 462.1 575.4 575.8 9753.3 11160.6 11642.3 11711.3 11917.6 13031.7 13502.7 13782.0 14062.5 14077.5 9907.7 9984.4 10004.3 10009.1 10041.1 3121.8 3139.8 3157.8 3164.2 3238.2 64778.0 66340.7 66877.3 67330.3 67568.7 10023.5 10052.7 10149.6 10348.7 10526.3 17280.9 17304.5 17304.7 17311.7 17315.5 6683.2 6727.6 6751.9 6967.8 6967.9 3591.4 3595.7 3623.5 3653.9 3654.0 2671.7 2770.1 2776.0 2779.3 2781.5 440.3 448.7 453.3 456.2 461.6 448.1 484.5 498.9 525.2 559.6 7690.4 7767.7 8042.0 8051.0 8208.2 468.7 469.7 510.6 540.8 550.8 3686.1 3705.6 3747.2 3810.8 3954.7 59317.0 59414.4 60767.7 80457.0 102115.0 67559.0 68041.7 69017.0 80140.6 81641.2 44806.8 62112.7 62258.0 64300.7 70548.3 7824.5 8273.8 8330.3 8418.3 8844.6 46107.0 47480.0 47584.3 48238.0 48274.5 62706.0 65426.8 65957.3 66555.7 69792.0 11165.0 11254.2 11350.1 11816.5 12392.9 12011.3 12011.6 12118.7 12900.9 13037.2 11654.8 11857.7 12329.7 12507.8 12804.2 10741.2 10819.2 11046.8 11358.9 11691.9 11995.6 12107.3 12685.3 12881.5 12886.6 9854.6 9947.9 9980.7 10193.7 10553.9 9238.3 9746.8 9845.1 9903.5 9908.1 08:52:41 INFO - PROCESS | 9588 | -------- Summary: end -------- 08:52:41 INFO - PROCESS | 9588 | 08:52:42 INFO - TEST-INFO | 9588: exit 0 08:52:42 INFO - TEST-OK | dromaeo_css | took 1121005ms 08:52:42 INFO - TEST-START | kraken 08:52:42 INFO - operating with platform_type : linux_ 08:52:42 INFO - Initialising browser for kraken test... 08:52:42 INFO - TEST-INFO | started process 9893 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKmZgU0/profile http://localhost:44324/getInfo.html) 08:52:47 INFO - PROCESS | 9893 | __metrics Screen width/height:1600/1200 08:52:47 INFO - PROCESS | 9893 | colorDepth:24 08:52:47 INFO - PROCESS | 9893 | Browser inner width/height: 1024/697 08:52:47 INFO - PROCESS | 9893 | __metrics 08:52:47 INFO - PROCESS | 9893 | 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" 08:52:47 INFO - PROCESS | 9893 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:52:48 INFO - PROCESS | 9893 | console.error: PushService: 08:52:48 INFO - PROCESS | 9893 | stateChangeProcessEnqueue: Error transitioning state 08:52:48 INFO - PROCESS | 9893 | UnknownError 08:52:49 INFO - TEST-INFO | 9893: exit 0 08:52:49 INFO - Browser initialized. 08:52:49 INFO - Running cycle 1/1 for kraken test... 08:52:49 INFO - TEST-INFO | started process 9972 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKmZgU0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:53:01 INFO - PROCESS | 9972 | RSS: Main: 174379008 08:53:01 INFO - PROCESS | 9972 | 08:53:04 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:44324/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44324/tests/kraken/driver.html?testName=audio-beat-detection) 08:53:05 INFO - PROCESS | 9972 | RSS: Main: 241795072 08:53:05 INFO - PROCESS | 9972 | 08:53:07 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:44324/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44324/tests/kraken/driver.html?testName=audio-dft) 08:53:08 INFO - PROCESS | 9972 | RSS: Main: 217808896 08:53:08 INFO - PROCESS | 9972 | 08:53:11 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:44324/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44324/tests/kraken/driver.html?testName=audio-fft) 08:53:12 INFO - PROCESS | 9972 | RSS: Main: 298860544 08:53:12 INFO - PROCESS | 9972 | 08:53:14 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:44324/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44324/tests/kraken/driver.html?testName=audio-oscillator) 08:53:15 INFO - PROCESS | 9972 | RSS: Main: 208904192 08:53:15 INFO - PROCESS | 9972 | 08:53:17 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:44324/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44324/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:53:18 INFO - PROCESS | 9972 | RSS: Main: 213504000 08:53:18 INFO - PROCESS | 9972 | 08:53:21 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:44324/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44324/tests/kraken/driver.html?testName=imaging-darkroom) 08:53:21 INFO - PROCESS | 9972 | RSS: Main: 250126336 08:53:21 INFO - PROCESS | 9972 | 08:53:25 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:44324/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44324/tests/kraken/driver.html?testName=imaging-desaturate) 08:53:25 INFO - PROCESS | 9972 | RSS: Main: 248442880 08:53:25 INFO - PROCESS | 9972 | 08:53:28 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:44324/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44324/tests/kraken/driver.html?testName=json-parse-financial) 08:53:29 INFO - PROCESS | 9972 | RSS: Main: 248627200 08:53:29 INFO - PROCESS | 9972 | 08:53:31 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:44324/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44324/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:53:31 INFO - PROCESS | 9972 | RSS: Main: 211582976 08:53:31 INFO - PROCESS | 9972 | 08:53:33 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:44324/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44324/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:53:34 INFO - PROCESS | 9972 | RSS: Main: 218824704 08:53:34 INFO - PROCESS | 9972 | 08:53:36 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:44324/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44324/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:53:37 INFO - PROCESS | 9972 | RSS: Main: 222330880 08:53:37 INFO - PROCESS | 9972 | 08:53:40 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:44324/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44324/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:53:40 INFO - PROCESS | 9972 | RSS: Main: 223776768 08:53:40 INFO - PROCESS | 9972 | 08:53:43 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:44324/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44324/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:53:44 INFO - PROCESS | 9972 | RSS: Main: 221978624 08:53:44 INFO - PROCESS | 9972 | 08:53:45 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:44324/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44324/tests/kraken/driver.html?testName=ai-astar) 08:53:46 INFO - PROCESS | 9972 | RSS: Main: 273346560 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | __start_tp_report 08:53:46 INFO - PROCESS | 9972 | _x_x_mozilla_page_load 08:53:46 INFO - PROCESS | 9972 | _x_x_mozilla_page_load_details 08:53:46 INFO - PROCESS | 9972 | |i|pagename|runs| 08:53:46 INFO - PROCESS | 9972 | |0;ai-astar;113;114;114;114;115;116;115;115;114;114 08:53:46 INFO - PROCESS | 9972 | |1;audio-beat-detection;153;129;127;158;130;126;123;125;155;125 08:53:46 INFO - PROCESS | 9972 | |2;audio-dft;161;160;161;162;162;162;161;162;161;161 08:53:46 INFO - PROCESS | 9972 | |3;audio-fft;77;76;77;121;77;76;76;76;99;77 08:53:46 INFO - PROCESS | 9972 | |4;audio-oscillator;83;83;119;84;84;85;114;84;83;116 08:53:46 INFO - PROCESS | 9972 | |5;imaging-gaussian-blur;118;117;117;117;118;117;117;118;120;118 08:53:46 INFO - PROCESS | 9972 | |6;imaging-darkroom;147;146;146;148;146;147;146;147;146;146 08:53:46 INFO - PROCESS | 9972 | |7;imaging-desaturate;96;95;94;95;95;95;94;95;94;95 08:53:46 INFO - PROCESS | 9972 | |8;json-parse-financial;64;64;64;64;65;65;64;66;65;65 08:53:46 INFO - PROCESS | 9972 | |9;json-stringify-tinderbox;66;66;66;66;66;66;67;66;67;66 08:53:46 INFO - PROCESS | 9972 | |10;stanford-crypto-aes;83;82;89;87;81;82;83;81;82;81 08:53:46 INFO - PROCESS | 9972 | |11;stanford-crypto-ccm;137;134;145;141;147;133;144;135;171;137 08:53:46 INFO - PROCESS | 9972 | |12;stanford-crypto-pbkdf2;170;171;169;169;174;175;173;171;175;170 08:53:46 INFO - PROCESS | 9972 | |13;stanford-crypto-sha256-iterative;69;68;69;69;69;69;68;69;68;68 08:53:46 INFO - PROCESS | 9972 | __end_tp_report 08:53:46 INFO - PROCESS | 9972 | __start_cc_report 08:53:46 INFO - PROCESS | 9972 | _x_x_mozilla_cycle_collect,1955 08:53:46 INFO - PROCESS | 9972 | __end_cc_report 08:53:46 INFO - PROCESS | 9972 | __startTimestamp1474127626234__endTimestamp 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | ------- Summary: start ------- 08:53:46 INFO - PROCESS | 9972 | Number of tests: 14 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | [#0] ai-astar Cycles:10 Average:114.40 Median:114.50 stddev:0.84 (0.7%) stddev-sans-first:0.73 08:53:46 INFO - PROCESS | 9972 | Values: 113.0 114.0 114.0 114.0 115.0 116.0 115.0 115.0 114.0 114.0 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:129.50 stddev:14.15 (10.9%) stddev-sans-first:13.45 08:53:46 INFO - PROCESS | 9972 | Values: 153.0 129.0 127.0 158.0 130.0 126.0 123.0 125.0 155.0 125.0 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.67 (0.4%) stddev-sans-first:0.71 08:53:46 INFO - PROCESS | 9972 | Values: 161.0 160.0 161.0 162.0 162.0 162.0 161.0 162.0 161.0 161.0 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | [#3] audio-fft Cycles:10 Average:83.20 Median:77.00 stddev:15.05 (19.6%) stddev-sans-first:15.80 08:53:46 INFO - PROCESS | 9972 | Values: 77.0 76.0 77.0 121.0 77.0 76.0 76.0 76.0 99.0 77.0 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:83.50 stddev:15.81 (18.9%) stddev-sans-first:16.31 08:53:46 INFO - PROCESS | 9972 | Values: 83.0 83.0 119.0 84.0 84.0 85.0 114.0 84.0 83.0 116.0 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.95 (0.8%) stddev-sans-first:1.00 08:53:46 INFO - PROCESS | 9972 | Values: 118.0 117.0 117.0 117.0 118.0 117.0 117.0 118.0 120.0 118.0 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 08:53:46 INFO - PROCESS | 9972 | Values: 147.0 146.0 146.0 148.0 146.0 147.0 146.0 147.0 146.0 146.0 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 08:53:46 INFO - PROCESS | 9972 | Values: 96.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:65.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 08:53:46 INFO - PROCESS | 9972 | Values: 64.0 64.0 64.0 64.0 65.0 65.0 64.0 66.0 65.0 65.0 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 08:53:46 INFO - PROCESS | 9972 | Values: 66.0 66.0 66.0 66.0 66.0 66.0 67.0 66.0 67.0 66.0 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:82.50 stddev:2.73 (3.3%) stddev-sans-first:2.89 08:53:46 INFO - PROCESS | 9972 | Values: 83.0 82.0 89.0 87.0 81.0 82.0 83.0 81.0 82.0 81.0 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | [#11] stanford-crypto-ccm Cycles:10 Average:142.40 Median:142.50 stddev:11.17 (7.8%) stddev-sans-first:11.67 08:53:46 INFO - PROCESS | 9972 | Values: 137.0 134.0 145.0 141.0 147.0 133.0 144.0 135.0 171.0 137.0 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:172.00 stddev:2.36 (1.4%) stddev-sans-first:2.42 08:53:46 INFO - PROCESS | 9972 | Values: 170.0 171.0 169.0 169.0 174.0 175.0 173.0 171.0 175.0 170.0 08:53:46 INFO - PROCESS | 9972 | 08:53:46 INFO - PROCESS | 9972 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:0.52 (0.7%) stddev-sans-first:0.53 08:53:46 INFO - PROCESS | 9972 | Values: 69.0 68.0 69.0 69.0 69.0 69.0 68.0 69.0 68.0 68.0 08:53:46 INFO - PROCESS | 9972 | -------- Summary: end -------- 08:53:46 INFO - PROCESS | 9972 | 08:53:47 INFO - TEST-INFO | 9972: exit 0 08:53:47 INFO - TEST-OK | kraken | took 64880ms 08:53:47 INFO - SUITE-END | took 1185s 08:53:47 INFO - Completed test suite (00:19:46) 08:53:47 INFO - javascript benchmark 08:53:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2542.1610868413427, 2612.2293923292627, 2647.0, 2656.8140230183894, 2667.413169102798, 4525.660575456845, 4594.931076033862, 4682.321063445798, 4701.7649117544115, 4706.62347012239, 4615.030843522856, 4665.253559430492, 4701.50634183411, 4717.481077081518, 4734.763261836912, 4347.7173983690955, 4348.695591308609, 4365.672574556905, 4379.321205213181, 4446.688731788776, 4349.630281426083, 4379.0585024219945, 4389.385486031958, 4389.9561004390025, 4430.512643609217, 4245.8513952011845, 4291.0774183550375, 4295.312749959991, 4346.8913277168, 4423.0, 4320.351947207912, 4324.978375108136, 4336.089421221547, 4340.978295108536, 4341.978290108561, 4284.228838809013, 4304.203722311351, 4305.246581848164, 4305.827766889321, 4309.525952145262, 4577.244754615484, 4602.585767280927, 4623.352730617717, 4689.9765501172615, 4696.835610753641, 4931.4821943696115, 4934.4572097069295, 4941.011797640486, 5003.249512573107, 5003.874903127415, 4962.60299176065, 4964.05682920244, 4972.87567810804, 4976.805566664011, 5034.823781167642, 4563.0, 4567.269236922077, 4616.422947131608, 4623.861284161447, 4695.178343789823, 5058.721770302669, 5118.744062796845, 5129.410117836496, 5140.203268493389, 5193.532582067632, 4864.318995340655, 4898.485659005753, 4899.412070551557, 4905.730184839868, 4969.453360130383, 4982.078315511642, 4995.725235112103, 4998.475160108136, 5001.0, 5063.240513922941, 4919.434265059493, 4971.353724015855, 4971.7016978980655, 5031.169856973632, 5034.395872495324, 4957.975210123998, 4989.12690279207, 5014.949850501448, 5066.189409694432, 5068.746562671851, 4213.9578604214175, 4219.683523735732, 4221.6200541951375, 4227.0, 4274.358846173037, 3602.171500554887, 3707.870224542182, 3711.6473934976134, 3736.308782875177, 3757.3800322946954, 4307.784610769511, 4340.131973605292, 4366.563343665608, 4366.803493842721, 4375.6280716138845, 5030.597552195815, 5034.244863270539, 5092.898142037213, 5095.261187127846, 5098.413682426494, 4316.460442444694, 4326.069894972596, 4358.585934336233, 4365.0, 4372.453443319585, 4332.328489084197, 4370.759608221515, 4418.734875907391, 4451.554844515523, 4510.864674059783, 4774.069056533943, 4838.153323168361, 4854.004928989478, 4912.066707325596, 4914.434839993446, 5137.2037334213255, 5144.434112247724, 5147.613928955268, 5148.43367229612, 5193.0, 4857.906970931512, 4865.635077369141, 4865.659403841697, 4868.440129385166, 4915.4592994771265, 4872.9269060964825, 4902.093112774148, 4934.062528119789, 4960.23116416956, 5114.028334616411, 5073.568746656576, 5074.873128171826, 5075.822346217865, 5087.669301495391, 5135.717535535581, 4925.088858561177, 4947.3568436104515, 4947.60419166473, 4950.430700469492, 4951.356323677899, 4695.248760198352, 4793.065352256416, 4854.660173787801, 4945.035718034948, 5006.148954677641, 4810.542998415145, 4816.710997340171, 4840.95159048405, 4873.610111191026, 4900.14247506692, 4929.16204245272, 4929.679570827885, 4939.382577177853, 4944.431390390151, 4958.900821983612, 4770.0936822003705, 4827.927581086356, 4831.444383895897, 4836.056968891033, 4905.043516514245], "value": 4616.848942930321, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.9318835145607, 553.9584531160164, 574.1387918122814, 577.751566826265, 585.0668184246126, 28808.27979300516, 30700.232494187658, 30710.84644576777, 31111.68888311116, 39062.21875562494, 40027.79986100073, 40696.761678107694, 40706.0, 40804.59195408045, 40866.0, 7526.473146879721, 7660.348870346, 7675.347595454393, 7678.155402905676, 7678.385729141655, 7218.133823941134, 7401.925980740178, 7547.094348678166, 9727.610895564168, 10017.799644007115, 10175.84736228957, 10181.6945491635, 10229.130523905476, 10238.846417303746, 10259.538320775546, 10057.748556286115, 10099.646512372105, 10114.393136411838, 10118.038786315361, 10122.89877101227, 9785.706428807147, 9850.113489785883, 9856.802863942681, 9859.30984831062, 9865.654702085392, 38537.61462385368, 38814.22371552581, 38863.0, 38866.417003744966, 38907.61092389068, 2692.0846912049997, 2828.2081017315186, 3234.951475727866, 3254.1539199807985, 3329.51722000311, 28874.422511549863, 28956.27609309763, 29067.12798616045, 29158.562621560694, 29382.706172938426, 3193.0899693587216, 3214.7428205743718, 3226.177324782189, 3230.07942736319, 3352.4468462703626, 248445.0, 249253.0, 249478.0, 249515.0, 249548.0, 27974.3006424841, 53403.73298133562, 53941.46058539443, 53950.73024634852, 54345.0, 30245.69754302429, 32216.0, 32283.51574726381, 32330.191745206557, 32347.0, 11539.249948753304, 11726.179167458367, 11731.23746956445, 11735.941320293345, 11738.237014594024, 8595.140485951355, 9476.905230947741, 9578.664746733832, 9607.567659455202, 9619.807603848023, 7236.131664200329, 7579.62101894903, 7809.882851757228, 7813.531188128731, 7861.606919654108, 3068.3556453144643, 3073.9855847570248, 3098.178982569621, 3102.0848849589424, 3110.144710204712, 753.2091304130641, 774.2605811450052, 833.6832003838502, 839.412411312084, 871.9738407847648, 27629.447411051668, 27874.442511150068, 27987.321396633255, 27997.44005119886, 28213.29466763306, 903.6746771162376, 928.8885333759993, 940.6002448959165, 972.5186032913753, 1007.6725064354114, 13847.515336963157, 14774.556763297118, 14845.703085938221, 14920.179390133648, 15107.093574385572, 374693.0, 378327.0, 379570.0, 380944.0, 381213.0, 49787.25319120216, 50288.0, 50315.49684503181, 50371.74814125979, 50396.24405633919, 1024.0680980307884, 1051.4795176387738, 1091.9617813376494, 1105.6075093341908, 1166.8949794518535, 28045.0, 29591.40817183601, 29653.0, 29915.850420747756, 30472.542911856784, 41548.58451415447, 42292.154156917306, 42393.576064238965, 42395.1520969585, 42450.575494245895, 302528.0, 306107.8167654974, 306391.0, 570123.0, 597354.0, 35489.82255088708, 36133.63866361303, 36510.63489365072, 36525.08687282839, 36904.630953691194, 40441.0, 40749.185016298914, 40782.59217407788, 41219.38170927498, 41359.1728165429, 34661.826690866386, 35222.11944701403, 35387.115322117155, 35574.11064723299, 35673.82163089168, 34691.826540867136, 34875.0, 34957.65042349646, 35007.64992350044, 35073.47389789104, 34096.0, 34482.137946551535, 35143.12142196363, 35278.82360588181, 35671.46492802558, 29375.265618359714, 31463.685363146076, 31848.681513184572, 32106.51840222352, 32146.0, 21811.563768724853, 21834.235801747498, 22132.66800997954, 22256.0, 22274.220402285842], "value": 16593.2793552066, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.51094526121364, 330.24374183120653, 332.3137720606945, 336.8719886443152, 347.3972657439342, 10735.892641073588, 15495.0, 16055.919720401382, 16164.030158190502, 17339.826601733977, 15256.389744410239, 15533.766993495079, 16413.261403236855, 17341.306347746075, 17559.0, 3994.600539945997, 4859.975700121495, 4890.529443034069, 5062.240663900426, 5064.113780088488, 4862.051899879525, 4965.031818795336, 4972.527609877074, 4973.254011898208, 4979.875503112415, 4735.455422626391, 4938.555530002299, 4938.580220681229, 4942.703437793726, 4942.851714448554, 4813.446453657822, 4887.95112048882, 4888.633352498577, 4894.559489645913, 4898.559129678329, 4330.653547716207, 4332.111917057027, 4333.414988976511, 4337.240982828024, 4338.154059958278, 19716.50708732293, 20223.898880505505, 20245.8987705062, 20339.593208135902, 20637.58724825495, 1643.8191798902112, 1774.4562230859235, 1856.5265857206462, 1886.311496303853, 1972.0829814136353, 8604.569771511462, 8639.136086391374, 8641.783955401103, 8836.911630883673, 12019.63941081769, 1680.2103011584536, 1745.2757105801077, 1753.7106377447706, 1763.867709921761, 1784.3308759215295, 66483.66758166226, 68090.31909680889, 69305.0, 69446.0, 69831.65084174597, 9538.570764315622, 9551.376266034791, 9723.173530250007, 9772.267079969031, 9958.15355694774, 13149.934250328688, 13166.670833229246, 13184.27486488233, 13191.868081319257, 13209.40557674907, 6515.381038801306, 6732.091167692397, 6852.554583952027, 6876.0, 6919.861602767916, 6270.216222972129, 6325.683715814191, 6332.620042797447, 6492.707828147744, 6501.382368674968, 4105.8973525662095, 4113.794310284474, 4158.792060397029, 4229.577042295808, 4247.086876321606, 1687.1985806741945, 1687.4009726547145, 1729.8962062276303, 1753.2022929567017, 1760.5510594798245, 715.5349023135004, 762.2301475509774, 767.1906139023384, 782.8003859016028, 785.4619585583894, 15469.226538673247, 15686.372545098067, 15745.0, 15789.684206316038, 16343.427980020642, 765.0640518741156, 766.8734658781239, 797.4218691448654, 799.564237490569, 826.9173082691683, 8584.399092063495, 8593.398462107718, 8951.686690965786, 8957.596908139149, 9115.635374584943, 368817.0, 377942.0, 381497.0, 382511.0, 383921.0, 48823.755881220364, 50289.0, 74799.0, 80232.79650805243, 85931.0, 853.7054716122927, 882.9514376709215, 885.2652298592153, 886.2806156057228, 886.822635472908, 13912.0, 14065.507707230197, 14102.36539355711, 14354.641133971734, 14841.0, 35676.0, 36698.44952325771, 38047.239055219296, 39955.60044399519, 41876.790616046725, 51906.0, 53627.73186134044, 54213.45786542242, 54535.72732136314, 55066.72466637641, 6591.242007169045, 6940.757073502403, 7166.641667916479, 7169.3906017986965, 7272.200057993511, 9742.805143896941, 10880.836787448354, 11082.833757493483, 11509.884901150881, 11771.411429428666, 9491.0, 9623.711288661352, 10002.899971000197, 10014.198864091, 10195.949020255148, 9263.814723705624, 9657.130858222512, 10059.899401005896, 10060.748481288027, 10122.595096196364, 9918.504074796376, 10238.18094552449, 10429.843552346872, 10483.68548943533, 10650.57397704083, 7024.192217895007, 7153.535020223669, 7173.246809084971, 7185.82035449097, 7307.086614173229, 9308.348415610842, 9975.251856110675, 10212.234082443698, 10334.5349459273, 10536.051755342056], "value": 7754.911825290594, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1208.7884620191464, 1267.961961141166, 1299.610116964911, 1339.5110784563633, 1622.716024695678, 5896.321922978858, 7193.928060719391, 7466.589337586431, 7486.513376630515, 7596.240375962401, 7461.4403919705965, 7534.3972482201425, 7534.547927124377, 7535.924640753591, 7536.869469579566, 2028.381343690174, 2050.425880753384, 2053.7638171610306, 2055.465578949469, 2056.1775289884017, 2003.8597298189134, 2052.1791283486577, 2054.7328847249837, 2055.239561362298, 2055.9074841632164, 2021.2723419568943, 2022.4842665120448, 2030.624334498115, 2033.4611327998093, 2034.9389518314474, 1972.8224459798614, 1974.8815071095637, 1975.8221760041665, 1978.8317992970615, 1981.435290942089, 1968.0651690447066, 1979.574391505833, 2012.5471768852037, 2027.168860767093, 2028.2354351713955, 7157.248488908694, 7443.0, 7458.291462311126, 7561.697532098709, 7563.432742544332, 4130.669546436308, 4287.056847493546, 4290.2706539888295, 4295.226859165349, 4300.182965236595, 2687.3281679580105, 2786.9303267418272, 2838.8722507487214, 2839.162447078117, 2853.9857300713365, 865.4547634989979, 866.7356456280831, 879.6349514951288, 891.7592250092504, 907.3829795738895, 9959.352642078315, 10102.444365559892, 10104.595816167344, 10105.595776168944, 10111.19110471168, 3599.262151258986, 3657.5428071491065, 3754.568224654145, 3790.128270497801, 3790.75360101598, 7996.120426753053, 8012.238837310446, 8018.238267364591, 8029.397795165386, 8033.678652853937, 4154.5222299435345, 4154.626083652425, 4157.417961485395, 4159.313713237282, 4160.105577300895, 3348.8325583720716, 3470.097774578577, 3728.683061939711, 3877.747946383476, 3880.670143037817, 3188.091393952712, 3192.3615276944706, 3289.802611843249, 3306.5866766654167, 3326.534285200074, 978.8287049766262, 988.1452543549777, 1006.6527048168371, 1008.2740426892625, 1016.5425558498705, 622.8038167977073, 713.8465229975476, 728.3626826526789, 738.9963050184822, 757.4092208077708, 4604.58558729716, 4618.861434156981, 4667.789949452283, 4689.390379250675, 4810.95189048112, 374.10402087002024, 376.70616918803375, 381.7270651484202, 383.54549858417306, 385.5354298070818, 3185.315157241158, 3252.9674703252663, 3262.918427039343, 3266.379387916336, 3290.851911663981, 8875.378723489293, 9291.070892910655, 9300.8604870927, 9311.487868167316, 9320.766980825534, 9212.263018958467, 9223.0, 9229.123233292827, 9238.030006849318, 9249.075092490697, 6990.335918087773, 7057.047298614724, 7060.7881763547175, 7067.187273463617, 7098.39663628587, 4760.476347601831, 4805.783739731651, 4837.733924634038, 4907.312976183266, 5039.798408063636, 8498.490090594583, 8560.57197140153, 8564.828703426021, 8567.785805354915, 8590.098039705741, 8445.577721114043, 8478.660853566034, 8664.046954835088, 8682.739517814476, 8710.604583739268, 6074.210352654127, 6141.140240366263, 6153.907691384722, 6157.630542167484, 6158.414950579688, 6111.633302001894, 6410.647414392253, 6690.6320152390135, 6743.325667433218, 6872.553284036521, 5882.705864706833, 5948.4943779779205, 5976.372480889618, 5991.191189189403, 6142.262928448615, 5831.79588714393, 6116.113163591343, 6139.416755408229, 6152.292486363946, 6233.407826256498, 5769.336526299361, 5779.508741756998, 5789.1605717171615, 5822.184894114743, 5866.794662186803, 4155.750654960712, 4254.361845723166, 4261.488621365332, 4291.721038132511, 4389.056352884145, 4099.405586190045, 4200.789960502024, 4204.726692764961, 4262.637675797592, 4460.286354183445], "value": 3732.544879622087, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.6486562066176, 277.0662866141105, 283.63977748259725, 284.44107329098324, 285.48897473522385, 4706.576408123268, 4739.194336962716, 4796.872734907294, 4802.97598512007, 4811.446683631379, 4729.787159577827, 4746.264329028997, 4756.5243475652505, 4757.666963312561, 4767.690100143497, 3522.7886326820344, 3630.3283892479976, 3640.3265395901713, 3758.5677647070524, 3763.1909139534996, 3560.6439356064448, 3632.9636703633023, 3641.1443310821937, 3641.1794102948525, 3715.9442608360896, 3657.1771351445846, 3683.5395575553057, 3684.2631473705233, 3686.705063594906, 3698.5931547529885, 3704.518412606371, 3704.592494825567, 3707.4068149096015, 3708.462272970418, 3708.5178926739623, 3189.9202519936957, 3189.9362012759616, 3198.088544764731, 3238.886638967648, 3274.705276525124, 4655.511171326996, 4663.137319595851, 4664.370310008139, 4667.27310894971, 4671.11248862715, 4207.684423668206, 4217.8312867485265, 4221.472315960505, 4231.376546541133, 4232.894177645553, 4672.859814205579, 4939.851804445872, 4942.654014219006, 4946.703197808143, 4948.604111671094, 3457.1529975155995, 3468.150303175713, 3470.9132271693156, 3471.6354782747953, 3471.7743346682387, 5208.817691380784, 5223.3209682741, 5237.607179461517, 5243.685378877241, 5246.160614301693, 3531.1172206948263, 3534.823258837048, 3537.911552211164, 3538.115471132217, 3568.1614820517157, 3509.9298014039578, 3560.8219589020446, 3560.8219589020446, 3577.0, 3578.7137029037613, 4078.898027549276, 4087.346024636044, 4095.97952010244, 4103.281925662997, 4105.67154627629, 4937.950620493749, 4939.87650308738, 4947.688862451453, 4954.950450495449, 4964.0, 1205.4756181061266, 1223.4127618743053, 1237.826704261404, 1240.9503619855286, 1243.6890777305673, 768.404486522938, 785.6464590933988, 788.3299195683737, 790.9564973926489, 794.8807678848219, 3954.406838974177, 3977.880663580097, 3983.780892050965, 4261.849300688797, 4372.322290044985, 5024.221245706921, 5143.0, 5146.845594632167, 5156.432792392834, 5166.656669266018, 4483.50681425043, 4487.596116349544, 4492.326151077365, 4494.70784399013, 4502.302143167814, 2660.773834224074, 2680.249530131566, 2714.5792402177694, 2714.9185524434297, 2744.67063952327, 4887.804487820518, 4894.363732714724, 4895.510448955147, 4907.901841963141, 4915.926261106087, 5261.789528418897, 5264.710440925785, 5265.709901074185, 5274.736263186825, 5277.815276465382, 4634.513376095529, 4835.927461088017, 4843.4430040545785, 4893.314935908976, 4895.681780684244, 5111.769970351269, 5117.974410127926, 5120.283160357479, 5125.974370128126, 5129.204973229155, 5028.974855125847, 5032.169692000779, 5033.219850923113, 5039.5968322534845, 5040.269160971712, 4688.921548043901, 4792.376990991149, 4847.854564363075, 4867.221244600847, 4873.634477414137, 3519.3665140273974, 3533.469979503095, 3535.1692352297705, 3540.415831387792, 3541.8229088544954, 5092.796288148433, 5093.71984540839, 5102.974485127551, 5121.436641969306, 5127.79488820443, 4811.975940120277, 4898.608111351155, 4957.578605818546, 4974.900501990012, 4980.8754781130765, 4965.925511117408, 4967.60259179272, 4969.1800852860315, 4969.776360063878, 4972.3038774572315, 4864.829730959399, 4873.537013983666, 4882.2432522959, 4887.633427492881, 4892.339534162985, 4763.7141771493825, 4925.945913533632, 4969.204927211629, 4970.229614409773, 4993.600511959107, 4633.606143477842, 4767.809287628456, 4786.396557153393, 4893.535114164149, 4900.779464924016], "value": 3713.551901760428, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.5483613109512, 598.3597550620832, 598.6527813867955, 617.65411369633, 629.8330942300291, 12233.449494772734, 12851.0, 13134.408951597177, 13194.736105277889, 13198.076134670577, 12323.69190770229, 12590.118691691585, 12601.11792174546, 12606.558770443036, 12611.43248553815, 592.2508027345411, 724.5399171526066, 739.3973911262331, 741.3735393592433, 743.1305372713938, 603.0291231117897, 610.8014895159091, 611.0071134406589, 717.2934659360526, 745.2845268542206, 783.5416281475344, 788.2314743125436, 788.530824159627, 789.0373744032271, 789.0570767932323, 789.0886026639183, 789.2699253190775, 789.3448437796615, 789.4394979564516, 789.5065584009993, 452.7555120235069, 458.63309352518183, 462.1081313065815, 575.4360726488065, 575.7812031428073, 9753.317267791257, 11160.553577856876, 11642.301461912313, 11711.297322160697, 11917.582884599084, 13031.739365212738, 13502.662433439242, 13782.0, 14062.507812226626, 14077.507287245, 9907.653232136912, 9984.35101718386, 10004.299699021072, 10009.099181073665, 10041.096301332842, 3121.7801379091684, 3139.7645176611845, 3157.8263195524237, 3164.240582260264, 3238.17426556229, 64778.0, 66340.6682966587, 66877.3312266876, 67330.3266967329, 67568.66215668939, 10023.498825058718, 10052.748681283027, 10149.64476243328, 10348.689539313833, 10526.263161578769, 17280.913595431943, 17304.480865574053, 17304.65390692204, 17311.740323895152, 17315.480535583953, 6683.2314283857, 6727.62998035103, 6751.864962700817, 6967.79096627102, 6967.895481567679, 3591.4074177760376, 3595.658412450813, 3623.5108260385036, 3653.926921461556, 3653.963460365362, 2671.6927553331225, 2770.1135636596096, 2775.9867648308223, 2779.3329600896045, 2781.4993301205977, 440.3131115459893, 448.74421579699913, 453.301915050818, 456.17432447270545, 461.62377662205193, 448.13957202171906, 484.48886424821444, 498.9226669866176, 525.1912055434588, 559.5998860814537, 7690.384769218449, 7767.650455729506, 8042.0, 8051.0, 8208.220219079178, 468.67427138139345, 469.73694730950774, 510.58897587441726, 540.7999040355094, 550.8182299841043, 3686.060054686038, 3705.629437056327, 3747.2318174774105, 3810.8475660973804, 3954.7033972451604, 59317.0, 59414.40585594089, 60767.696161519794, 80457.0, 102115.0, 67559.0, 68041.65979170073, 69017.0, 80140.5992970043, 81641.18358816455, 44806.77596611996, 62112.68943655343, 62258.0, 64300.678496607215, 70548.2945170552, 7824.452288339761, 8273.751787446387, 8330.291925186428, 8418.284445822173, 8844.601992910462, 46107.0, 47480.0, 47584.2862357058, 48238.0, 48274.517254827006, 62706.0, 65426.784455476656, 65957.34042659512, 66555.66722166358, 69792.0, 11165.0, 11254.155938304824, 11350.143568766256, 11816.468258928197, 12392.938035309766, 12011.339376334385, 12011.63965081049, 12118.697032574257, 12900.935495322463, 13037.15258508194, 11654.825177622512, 11857.703557411134, 12329.691757706129, 12507.812382814447, 12804.167729097579, 10741.24811263204, 10819.18856085781, 11046.834297485384, 11358.943205284251, 11691.941540292244, 11995.64013079609, 12107.273563586214, 12685.302308372758, 12881.549145779853, 12886.613401597968, 9854.605815767289, 9947.900520994697, 9980.650677226262, 10193.694189174337, 10553.947230263799, 9238.260939124988, 9746.80506389854, 9845.064718851698, 9903.5305818001, 9908.108270255712], "value": 6229.673819428767, "unit": "score"}], "name": "dromaeo_css", "value": 6095.758151568003, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 115.0, 116.0, 115.0, 115.0, 114.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 129.0, 127.0, 158.0, 130.0, 126.0, 123.0, 125.0, 155.0, 125.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 162.0, 162.0, 162.0, 161.0, 162.0, 161.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 121.0, 77.0, 76.0, 76.0, 76.0, 99.0, 77.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 119.0, 84.0, 84.0, 85.0, 114.0, 84.0, 83.0, 116.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 118.0, 120.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 148.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 64.0, 65.0, 65.0, 64.0, 66.0, 65.0, 65.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0, 67.0, 66.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 89.0, 87.0, 81.0, 82.0, 83.0, 81.0, 82.0, 81.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 134.0, 145.0, 141.0, 147.0, 133.0, 144.0, 135.0, 171.0, 137.0], "value": 142.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 169.0, 169.0, 174.0, 175.0, 173.0, 171.0, 175.0, 170.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 69.0, 69.0, 69.0, 68.0, 69.0, 68.0, 68.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1543.1, "alertThreshold": 2.0}]} 08:53:47 INFO - Return code: 0 08:53:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:53:48 INFO - # TBPL SUCCESS # 08:53:48 INFO - Running post-action listener: _resource_record_post_action 08:53:48 INFO - [mozharness: 2016-09-17 15:53:48.038394Z] Finished run-tests step (success) 08:53:48 INFO - Running post-run listener: _resource_record_post_run 08:53:48 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 8192; Write bytes: 399548416; Read time: 196; Write time: 304356 08:53:48 INFO - TinderboxPrint: CPU usage
13.1% 08:53:48 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 196 08:53:48 INFO - TinderboxPrint: I/O write bytes / time
399,548,416 / 304,356 08:53:48 INFO - TinderboxPrint: CPU idle
8,343.8 (86.9%) 08:53:48 INFO - TinderboxPrint: CPU user
1,202.6 (12.5%) 08:53:48 INFO - TinderboxPrint: Swap in / out
0 / 0 08:53:48 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 106901504; Read time: 0; Write time: 70672 08:53:48 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292646912; Read time: 188; Write time: 233684 08:53:48 INFO - Running post-run listener: _upload_blobber_files 08:53:48 INFO - Blob upload gear active. 08:53:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:53: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 08:53: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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:53: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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:53:49 INFO - (blobuploader) - INFO - Open directory for files ... 08:53:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:53:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:53:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:49 INFO - (blobuploader) - INFO - Done attempting. 08:53:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:53:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:53:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:54 INFO - (blobuploader) - INFO - Done attempting. 08:53:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:53:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:53:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:55 INFO - (blobuploader) - INFO - Done attempting. 08:53:55 INFO - (blobuploader) - INFO - Iteration through files over. 08:53:55 INFO - Return code: 0 08:53:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:53:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:53:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f2be84473e65ed0cb241f89734ddc304a921f6a4aca74b21f4d046c4dc8b369b3b692255f30be191288bb58dbf0756e58f80773196ede2e68a4f66df11bbb9c0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ff61c6983a67731a882a63c4a62760a7ad1bc36876c98c3647eb877d70a3115c63a66f7ccac185f1024fb043424813fb3f398c01e7e5b7c05086120c1646167f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2d659e9aa21697d3b95138f1e827a23efd9a976fb62673fc367da89937a111119221131c33bd585659c5c9002b2587ebc4ac6c378a489b7f7770e5ec8c8c2536"} 08:53:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:53:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:53:55 INFO - Contents: 08:53:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f2be84473e65ed0cb241f89734ddc304a921f6a4aca74b21f4d046c4dc8b369b3b692255f30be191288bb58dbf0756e58f80773196ede2e68a4f66df11bbb9c0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ff61c6983a67731a882a63c4a62760a7ad1bc36876c98c3647eb877d70a3115c63a66f7ccac185f1024fb043424813fb3f398c01e7e5b7c05086120c1646167f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2d659e9aa21697d3b95138f1e827a23efd9a976fb62673fc367da89937a111119221131c33bd585659c5c9002b2587ebc4ac6c378a489b7f7770e5ec8c8c2536"} 08:53:55 INFO - Running post-run listener: copy_logs_to_upload_dir 08:53:55 INFO - Copying logs to upload dir... 08:53:55 INFO - mkdir: /builds/slave/test/build/upload/logs 08:53:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.195158 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-09-17 08:53:55.188078) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 08:53:55.195100) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454 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-Non-PGO/sha512/f2be84473e65ed0cb241f89734ddc304a921f6a4aca74b21f4d046c4dc8b369b3b692255f30be191288bb58dbf0756e58f80773196ede2e68a4f66df11bbb9c0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ff61c6983a67731a882a63c4a62760a7ad1bc36876c98c3647eb877d70a3115c63a66f7ccac185f1024fb043424813fb3f398c01e7e5b7c05086120c1646167f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2d659e9aa21697d3b95138f1e827a23efd9a976fb62673fc367da89937a111119221131c33bd585659c5c9002b2587ebc4ac6c378a489b7f7770e5ec8c8c2536"} build_url:https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011252 build_url: 'https://queue.taskcluster.net/v1/task/ZoyispkxSwGHFi8vGRZQBg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f2be84473e65ed0cb241f89734ddc304a921f6a4aca74b21f4d046c4dc8b369b3b692255f30be191288bb58dbf0756e58f80773196ede2e68a4f66df11bbb9c0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ff61c6983a67731a882a63c4a62760a7ad1bc36876c98c3647eb877d70a3115c63a66f7ccac185f1024fb043424813fb3f398c01e7e5b7c05086120c1646167f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2d659e9aa21697d3b95138f1e827a23efd9a976fb62673fc367da89937a111119221131c33bd585659c5c9002b2587ebc4ac6c378a489b7f7770e5ec8c8c2536"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 08:53:55.222832) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:53:55.223170) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1474126387.601052-2076505454 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004412 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:53:55.302546) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 08:53:55.302867) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 08:53:55.303252) ========= ========= Total master_lag: 0.18 =========