builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-002 starttime: 1474311009.35 results: success (0) buildid: 20160919112617 builduid: 89bb2c5f0d144cde806a522bcc213dcc revision: 690b85efea256f02bb46f032397257aa7b99de3a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 11:50:09.349860) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 11:50:09.350298) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 11:50:09.350575) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 11:50:09.393749) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 11:50:09.394011) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 11:50:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 23.1M=0.001s 2016-09-19 11:50:09 (23.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109140 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 11:50:09.517191) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 11:50:09.517501) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017457 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 11:50:09.566104) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 11:50:09.566425) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 690b85efea256f02bb46f032397257aa7b99de3a --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 690b85efea256f02bb46f032397257aa7b99de3a --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=a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 11:50:09,779 truncating revision to first 12 chars 2016-09-19 11:50:09,779 Setting DEBUG logging. 2016-09-19 11:50:09,779 attempt 1/10 2016-09-19 11:50:09,779 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/690b85efea25?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 11:50:11,145 unpacking tar archive at: fx-team-690b85efea25/testing/mozharness/ program finished with exit code 0 elapsedTime=1.734869 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 11:50:11.314878) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 11:50:11.315200) ========= script_repo_revision: 690b85efea256f02bb46f032397257aa7b99de3a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 11:50:11.315596) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 11:50:11.316164) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 11:50:11.326598) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-09-19 11:50:11.326929) ========= /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=a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:50:11 INFO - MultiFileLogger online at 20160919 11:50:11 in /builds/slave/test 11:50:11 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 11:50:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:50:11 INFO - {'append_to_log': False, 11:50:11 INFO - 'base_work_dir': '/builds/slave/test', 11:50:11 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 11:50:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:50:11 INFO - 'branch': 'Fx-Team-Non-PGO', 11:50:11 INFO - 'buildbot_json_path': 'buildprops.json', 11:50:11 INFO - 'config_files': ('talos/linux_config.py',), 11:50:11 INFO - 'default_actions': ('clobber', 11:50:11 INFO - 'read-buildbot-config', 11:50:11 INFO - 'download-and-extract', 11:50:11 INFO - 'populate-webroot', 11:50:11 INFO - 'create-virtualenv', 11:50:11 INFO - 'install', 11:50:11 INFO - 'run-tests'), 11:50:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:50:11 INFO - 'download_minidump_stackwalk': True, 11:50:11 INFO - 'download_symbols': 'ondemand', 11:50:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:50:11 INFO - 'tooltool.py': '/tools/tooltool.py', 11:50:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:50:11 INFO - '/tools/misc-python/virtualenv.py')}, 11:50:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:50:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:50:11 INFO - 'installer_path': 'installer.exe', 11:50:11 INFO - 'log_level': 'info', 11:50:11 INFO - 'log_name': 'talos', 11:50:11 INFO - 'log_to_console': True, 11:50:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:50:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:50:11 INFO - 'opt_config_files': (), 11:50:11 INFO - 'pip_index': False, 11:50:11 INFO - 'sps_profile': False, 11:50:11 INFO - 'sps_profile_interval': 0, 11:50:11 INFO - 'suite': 'dromaeojs', 11:50:11 INFO - 'system_bits': '32', 11:50:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:50:11 INFO - 'title': 'talos-linux64-ix-002', 11:50:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:50:11 INFO - 'use_talos_json': True, 11:50:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:50:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:50:11 INFO - 'work_dir': 'build'} 11:50:11 INFO - [mozharness: 2016-09-19 18:50:11.641737Z] Running clobber step. 11:50:11 INFO - Running pre-action listener: _resource_record_pre_action 11:50:11 INFO - Running main action method: clobber 11:50:11 INFO - rmtree: /builds/slave/test/build 11:50:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:50:12 INFO - Running post-action listener: _resource_record_post_action 11:50:12 INFO - [mozharness: 2016-09-19 18:50:12.612479Z] Finished clobber step (success) 11:50:12 INFO - [mozharness: 2016-09-19 18:50:12.612578Z] Running read-buildbot-config step. 11:50:12 INFO - Running pre-action listener: _resource_record_pre_action 11:50:12 INFO - Running main action method: read_buildbot_config 11:50:12 INFO - Using buildbot properties: 11:50:12 INFO - { 11:50:12 INFO - "project": "", 11:50:12 INFO - "product": "firefox", 11:50:12 INFO - "script_repo_revision": "production", 11:50:12 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 11:50:12 INFO - "repository": "", 11:50:12 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 11:50:12 INFO - "buildid": "20160919112617", 11:50:12 INFO - "pgo_build": "False", 11:50:12 INFO - "basedir": "/builds/slave/test", 11:50:12 INFO - "buildnumber": 3333, 11:50:12 INFO - "slavename": "talos-linux64-ix-002", 11:50:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:50:12 INFO - "platform": "ubuntu64_hw", 11:50:12 INFO - "scheduler": "tests-fx-team-linux64-talos", 11:50:12 INFO - "branch": "fx-team", 11:50:12 INFO - "repo_path": "integration/fx-team", 11:50:12 INFO - "revision": "690b85efea256f02bb46f032397257aa7b99de3a", 11:50:12 INFO - "stage_platform": "linux64", 11:50:12 INFO - "builduid": "89bb2c5f0d144cde806a522bcc213dcc", 11:50:12 INFO - "slavebuilddir": "test" 11:50:12 INFO - } 11:50:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 11:50:12 INFO - Running post-action listener: _resource_record_post_action 11:50:12 INFO - [mozharness: 2016-09-19 18:50:12.615032Z] Finished read-buildbot-config step (success) 11:50:12 INFO - [mozharness: 2016-09-19 18:50:12.615121Z] Running download-and-extract step. 11:50:12 INFO - Running pre-action listener: _resource_record_pre_action 11:50:12 INFO - Running main action method: download_and_extract 11:50:12 INFO - mkdir: /builds/slave/test/build/tests 11:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:50:12 INFO - trying https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 11:50:12 INFO - Downloading https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/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 11:50:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/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 11:50:13 INFO - Downloaded 1361 bytes. 11:50:13 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 11:50:13 INFO - Using the following test package requirements: 11:50:13 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 11:50:13 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:50:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:50:13 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:50:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:50:13 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:50:13 INFO - u'jsshell-linux-x86_64.zip'], 11:50:13 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:50:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:50:13 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 11:50:13 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:50:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:50:13 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:50:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:50:13 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:50:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:50:13 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:50:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:50:13 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:50:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 11:50:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:50:13 INFO - Fetch https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 11:50:13 INFO - Expected file size: 20365610 11:50:18 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 11:50:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 11:50:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:50:18 INFO - Fetch https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 11:50:18 INFO - Expected file size: 20365610 11:50:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 11:50:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:50:23 INFO - Fetch https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 11:50:23 INFO - Expected file size: 14414059 11:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:50:24 INFO - trying https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 11:50:24 INFO - Downloading https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/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 11:50:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/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 11:50:27 INFO - Downloaded 55594068 bytes. 11:50:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 11:50:27 INFO - mkdir: /builds/slave/test/properties 11:50:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:50:27 INFO - Writing to file /builds/slave/test/properties/build_url 11:50:27 INFO - Contents: 11:50:27 INFO - build_url:https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 11:50:27 INFO - Running post-action listener: _resource_record_post_action 11:50:27 INFO - Running post-action listener: set_extra_try_arguments 11:50:27 INFO - [mozharness: 2016-09-19 18:50:27.713711Z] Finished download-and-extract step (success) 11:50:27 INFO - [mozharness: 2016-09-19 18:50:27.713940Z] Running populate-webroot step. 11:50:27 INFO - Running pre-action listener: _resource_record_pre_action 11:50:27 INFO - Running main action method: populate_webroot 11:50:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:50:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:50:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:50:27 INFO - '--tppagecycles', 11:50:27 INFO - '7'], 11:50:27 INFO - 'tests': ['tsvgm']}}, 11:50:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:50:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:50:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:50:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:50:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:50:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:50:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:27 INFO - 'talos_options': ['--disable-e10s'], 11:50:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:50:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:50:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:27 INFO - 'talos_options': ['--disable-e10s'], 11:50:27 INFO - 'tests': ['damp', 'tps']}, 11:50:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:27 INFO - 'tests': ['damp', 'tps']}, 11:50:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:50:27 INFO - 'tests': ['dromaeo_dom']}, 11:50:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:50:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:50:27 INFO - 'tests': ['basic_compositor_video']}, 11:50:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:50:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:50:27 INFO - 'tests': ['a11yr', 11:50:27 INFO - 'ts_paint', 11:50:27 INFO - 'tpaint', 11:50:27 INFO - 'sessionrestore', 11:50:27 INFO - 'sessionrestore_no_auto_restore', 11:50:27 INFO - 'tabpaint']}, 11:50:27 INFO - 'other-e10s': {'tests': ['a11yr', 11:50:27 INFO - 'ts_paint', 11:50:27 INFO - 'tpaint', 11:50:27 INFO - 'sessionrestore', 11:50:27 INFO - 'sessionrestore_no_auto_restore', 11:50:27 INFO - 'tabpaint']}, 11:50:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:50:27 INFO - 'ts_paint', 11:50:27 INFO - 'tpaint', 11:50:27 INFO - 'sessionrestore', 11:50:27 INFO - 'sessionrestore_no_auto_restore', 11:50:27 INFO - 'tabpaint']}, 11:50:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:50:27 INFO - 'ts_paint', 11:50:27 INFO - 'tpaint', 11:50:27 INFO - 'sessionrestore', 11:50:27 INFO - 'sessionrestore_no_auto_restore', 11:50:27 INFO - 'tabpaint']}, 11:50:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:50:27 INFO - 'tests': ['a11yr', 11:50:27 INFO - 'ts_paint', 11:50:27 INFO - 'tpaint', 11:50:27 INFO - 'sessionrestore', 11:50:27 INFO - 'sessionrestore_no_auto_restore', 11:50:27 INFO - 'tabpaint']}, 11:50:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:50:27 INFO - 'tests': ['a11yr', 11:50:27 INFO - 'ts_paint', 11:50:27 INFO - 'tpaint', 11:50:27 INFO - 'sessionrestore', 11:50:27 INFO - 'sessionrestore_no_auto_restore', 11:50:27 INFO - 'tabpaint']}, 11:50:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:50:27 INFO - 'tests': ['tsvgx', 11:50:27 INFO - 'tsvgr_opacity', 11:50:27 INFO - 'tart', 11:50:27 INFO - 'tscrollx', 11:50:27 INFO - 'cart']}, 11:50:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:50:27 INFO - 'tsvgr_opacity', 11:50:27 INFO - 'tart', 11:50:27 INFO - 'tscrollx', 11:50:27 INFO - 'cart']}, 11:50:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:27 INFO - 'talos_options': ['--disable-e10s'], 11:50:27 INFO - 'tests': ['tp5o']}, 11:50:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:27 INFO - 'tests': ['tp5o']}, 11:50:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:27 INFO - 'talos_options': ['--disable-e10s', 11:50:27 INFO - '--xperf_path', 11:50:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:50:27 INFO - 'tests': ['tp5n']}, 11:50:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:27 INFO - 'talos_options': ['--xperf_path', 11:50:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:50:27 INFO - 'tests': ['tp5n']}}, 11:50:27 INFO - 'talos.zip': {'path': '', 11:50:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:50:27 INFO - Running post-action listener: _resource_record_post_action 11:50:27 INFO - [mozharness: 2016-09-19 18:50:27.730205Z] Finished populate-webroot step (success) 11:50:27 INFO - [mozharness: 2016-09-19 18:50:27.730313Z] Running create-virtualenv step. 11:50:27 INFO - Running pre-action listener: _resource_record_pre_action 11:50:27 INFO - Running main action method: create_virtualenv 11:50:27 INFO - Creating virtualenv /builds/slave/test/build/venv 11:50:27 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 11:50:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:50:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:50:27 INFO - Using real prefix '/usr' 11:50:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:50:28 INFO - Installing distribute.............................................................................................................................................................................................done. 11:50:30 INFO - Installing pip.................done. 11:50:30 INFO - Return code: 0 11:50:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:50:30 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')]} 11:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:30 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')]} 11:50:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:30 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 0x7fc0df433f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f27ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f24f60>, '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 0x1f25d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f261d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', '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 11:50:30 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 11:50:30 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 11:50:30 INFO - Using env: {'DISPLAY': ':0', 11:50:30 INFO - 'HOME': '/home/cltbld', 11:50:30 INFO - 'LANG': 'en_US.UTF-8', 11:50:30 INFO - 'LANGUAGE': 'en_US:en', 11:50:30 INFO - 'LOGNAME': 'cltbld', 11:50:30 INFO - 'MAIL': '/var/mail/cltbld', 11:50:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:30 INFO - 'MOZ_NO_REMOTE': '1', 11:50:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:30 INFO - 'NO_EM_RESTART': '1', 11:50:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:30 INFO - 'PWD': '/builds/slave/test', 11:50:30 INFO - 'SHELL': '/bin/bash', 11:50:30 INFO - 'SHLVL': '1', 11:50:30 INFO - 'TERM': 'linux', 11:50:30 INFO - 'TMOUT': '86400', 11:50:30 INFO - 'USER': 'cltbld', 11:50:30 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', 11:50:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:30 INFO - '_': '/tools/buildbot/bin/python'} 11:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:30 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 11:50:30 INFO - Cleaning up... 11:50:30 INFO - Return code: 0 11:50:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:50:30 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')]} 11:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:30 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')]} 11:50:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:30 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 0x7fc0df433f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f27ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f24f60>, '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 0x1f25d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f261d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', '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 11:50:30 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 11:50:30 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 11:50:30 INFO - Using env: {'DISPLAY': ':0', 11:50:30 INFO - 'HOME': '/home/cltbld', 11:50:30 INFO - 'LANG': 'en_US.UTF-8', 11:50:30 INFO - 'LANGUAGE': 'en_US:en', 11:50:30 INFO - 'LOGNAME': 'cltbld', 11:50:30 INFO - 'MAIL': '/var/mail/cltbld', 11:50:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:30 INFO - 'MOZ_NO_REMOTE': '1', 11:50:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:30 INFO - 'NO_EM_RESTART': '1', 11:50:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:30 INFO - 'PWD': '/builds/slave/test', 11:50:30 INFO - 'SHELL': '/bin/bash', 11:50:30 INFO - 'SHLVL': '1', 11:50:30 INFO - 'TERM': 'linux', 11:50:30 INFO - 'TMOUT': '86400', 11:50:30 INFO - 'USER': 'cltbld', 11:50:30 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', 11:50:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:30 INFO - '_': '/tools/buildbot/bin/python'} 11:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:30 INFO - Downloading/unpacking psutil>=3.1.1 11:50:30 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 11:50:30 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 11:50:30 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 11:50:30 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 11:50:30 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 11:50:30 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 11:50:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:50:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:50:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:50:32 INFO - Installing collected packages: psutil 11:50:32 INFO - Running setup.py install for psutil 11:50:32 INFO - building 'psutil._psutil_linux' extension 11:50:32 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 11:50:32 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 11:50:33 INFO - building 'psutil._psutil_posix' extension 11:50:33 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 11:50:33 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 11:50:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:50:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:50:33 INFO - Successfully installed psutil 11:50:33 INFO - Cleaning up... 11:50:33 INFO - Return code: 0 11:50:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:50: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')]} 11:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50: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')]} 11:50:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0df433f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f27ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f24f60>, '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 0x1f25d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f261d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', '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 11:50: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:50: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 mozsystemmonitor==0.3 11:50:33 INFO - Using env: {'DISPLAY': ':0', 11:50:33 INFO - 'HOME': '/home/cltbld', 11:50:33 INFO - 'LANG': 'en_US.UTF-8', 11:50:33 INFO - 'LANGUAGE': 'en_US:en', 11:50:33 INFO - 'LOGNAME': 'cltbld', 11:50:33 INFO - 'MAIL': '/var/mail/cltbld', 11:50:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:33 INFO - 'MOZ_NO_REMOTE': '1', 11:50:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:33 INFO - 'NO_EM_RESTART': '1', 11:50:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:33 INFO - 'PWD': '/builds/slave/test', 11:50:33 INFO - 'SHELL': '/bin/bash', 11:50:33 INFO - 'SHLVL': '1', 11:50:33 INFO - 'TERM': 'linux', 11:50:33 INFO - 'TMOUT': '86400', 11:50:33 INFO - 'USER': 'cltbld', 11:50:33 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', 11:50:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:33 INFO - '_': '/tools/buildbot/bin/python'} 11:50:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:50: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 11:50: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 11:50: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 11:50: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 11:50: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 11:50: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 11:50:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:50:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:50:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:50:35 INFO - Installing collected packages: mozsystemmonitor 11:50:35 INFO - Running setup.py install for mozsystemmonitor 11:50:35 INFO - Successfully installed mozsystemmonitor 11:50:35 INFO - Cleaning up... 11:50:35 INFO - Return code: 0 11:50:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:50:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:35 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 0x7fc0df433f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f27ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f24f60>, '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 0x1f25d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f261d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', '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 11:50:35 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 11:50:35 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 11:50:35 INFO - Using env: {'DISPLAY': ':0', 11:50:35 INFO - 'HOME': '/home/cltbld', 11:50:35 INFO - 'LANG': 'en_US.UTF-8', 11:50:35 INFO - 'LANGUAGE': 'en_US:en', 11:50:35 INFO - 'LOGNAME': 'cltbld', 11:50:35 INFO - 'MAIL': '/var/mail/cltbld', 11:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:35 INFO - 'MOZ_NO_REMOTE': '1', 11:50:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:35 INFO - 'NO_EM_RESTART': '1', 11:50:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:35 INFO - 'PWD': '/builds/slave/test', 11:50:35 INFO - 'SHELL': '/bin/bash', 11:50:35 INFO - 'SHLVL': '1', 11:50:35 INFO - 'TERM': 'linux', 11:50:35 INFO - 'TMOUT': '86400', 11:50:35 INFO - 'USER': 'cltbld', 11:50:35 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', 11:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:35 INFO - '_': '/tools/buildbot/bin/python'} 11:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:35 INFO - Downloading/unpacking jsonschema==2.5.1 11:50:35 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 11:50:35 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 11:50:35 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 11:50:35 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 11:50:35 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 11:50:35 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 11:50:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:50:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:37 INFO - Downloading functools32-3.2.3-2.tar.gz 11:50:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:50:37 INFO - warning: no files found matching '*.txt' 11:50:37 INFO - no previously-included directories found matching 'build' 11:50:37 INFO - no previously-included directories found matching 'dist' 11:50:37 INFO - no previously-included directories found matching '.git*' 11:50:37 INFO - Installing collected packages: jsonschema, functools32 11:50:38 INFO - Running setup.py install for functools32 11:50:38 INFO - warning: no files found matching '*.txt' 11:50:38 INFO - no previously-included directories found matching 'build' 11:50:38 INFO - no previously-included directories found matching 'dist' 11:50:38 INFO - no previously-included directories found matching '.git*' 11:50:38 INFO - Successfully installed jsonschema functools32 11:50:38 INFO - Cleaning up... 11:50:38 INFO - Return code: 0 11:50:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:50:38 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')]} 11:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:38 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')]} 11:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:38 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 0x7fc0df433f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f27ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f24f60>, '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 0x1f25d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f261d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', '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 11:50:38 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 11:50:38 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 11:50:38 INFO - Using env: {'DISPLAY': ':0', 11:50:38 INFO - 'HOME': '/home/cltbld', 11:50:38 INFO - 'LANG': 'en_US.UTF-8', 11:50:38 INFO - 'LANGUAGE': 'en_US:en', 11:50:38 INFO - 'LOGNAME': 'cltbld', 11:50:38 INFO - 'MAIL': '/var/mail/cltbld', 11:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:38 INFO - 'MOZ_NO_REMOTE': '1', 11:50:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:38 INFO - 'NO_EM_RESTART': '1', 11:50:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:38 INFO - 'PWD': '/builds/slave/test', 11:50:38 INFO - 'SHELL': '/bin/bash', 11:50:38 INFO - 'SHLVL': '1', 11:50:38 INFO - 'TERM': 'linux', 11:50:38 INFO - 'TMOUT': '86400', 11:50:38 INFO - 'USER': 'cltbld', 11:50:38 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', 11:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:38 INFO - '_': '/tools/buildbot/bin/python'} 11:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:50:38 INFO - Cleaning up... 11:50:38 INFO - Return code: 0 11:50:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:50:38 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')]} 11:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:38 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')]} 11:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:38 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 0x7fc0df433f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f27ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f24f60>, '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 0x1f25d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f261d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', '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 11:50:38 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 11:50:38 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 11:50:38 INFO - Using env: {'DISPLAY': ':0', 11:50:38 INFO - 'HOME': '/home/cltbld', 11:50:38 INFO - 'LANG': 'en_US.UTF-8', 11:50:38 INFO - 'LANGUAGE': 'en_US:en', 11:50:38 INFO - 'LOGNAME': 'cltbld', 11:50:38 INFO - 'MAIL': '/var/mail/cltbld', 11:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:38 INFO - 'MOZ_NO_REMOTE': '1', 11:50:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:38 INFO - 'NO_EM_RESTART': '1', 11:50:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:38 INFO - 'PWD': '/builds/slave/test', 11:50:38 INFO - 'SHELL': '/bin/bash', 11:50:38 INFO - 'SHLVL': '1', 11:50:38 INFO - 'TERM': 'linux', 11:50:38 INFO - 'TMOUT': '86400', 11:50:38 INFO - 'USER': 'cltbld', 11:50:38 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', 11:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:38 INFO - '_': '/tools/buildbot/bin/python'} 11:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:38 INFO - Downloading/unpacking blobuploader==1.2.4 11:50:38 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 11:50:38 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 11:50:38 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 11:50:38 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 11:50:38 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 11:50:38 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 11:50:40 INFO - Downloading blobuploader-1.2.4.tar.gz 11:50:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:50:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:50:40 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 11:50:40 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 11:50:40 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 11:50:40 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 11:50:40 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 11:50:40 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 11:50:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:50:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:50:40 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 11:50:40 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 11:50:40 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 11:50:40 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 11:50:40 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 11:50:40 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 11:50:41 INFO - Downloading docopt-0.6.1.tar.gz 11:50:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:50:41 INFO - Installing collected packages: blobuploader, requests, docopt 11:50:41 INFO - Running setup.py install for blobuploader 11:50:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:50:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:50:41 INFO - Running setup.py install for requests 11:50:41 INFO - Running setup.py install for docopt 11:50:41 INFO - Successfully installed blobuploader requests docopt 11:50:41 INFO - Cleaning up... 11:50:41 INFO - Return code: 0 11:50:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:50:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:50:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:41 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 0x7fc0df433f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f27ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f24f60>, '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 0x1f25d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f261d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', '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 11:50:41 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 11:50:41 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 11:50:41 INFO - Using env: {'DISPLAY': ':0', 11:50:41 INFO - 'HOME': '/home/cltbld', 11:50:41 INFO - 'LANG': 'en_US.UTF-8', 11:50:41 INFO - 'LANGUAGE': 'en_US:en', 11:50:41 INFO - 'LOGNAME': 'cltbld', 11:50:41 INFO - 'MAIL': '/var/mail/cltbld', 11:50:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:41 INFO - 'MOZ_NO_REMOTE': '1', 11:50:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:41 INFO - 'NO_EM_RESTART': '1', 11:50:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:41 INFO - 'PWD': '/builds/slave/test', 11:50:41 INFO - 'SHELL': '/bin/bash', 11:50:41 INFO - 'SHLVL': '1', 11:50:41 INFO - 'TERM': 'linux', 11:50:41 INFO - 'TMOUT': '86400', 11:50:41 INFO - 'USER': 'cltbld', 11:50:41 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', 11:50:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:41 INFO - '_': '/tools/buildbot/bin/python'} 11:50:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:50:42 INFO - Running setup.py (path:/tmp/pip-8_RYwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:50:42 INFO - Running setup.py (path:/tmp/pip-dAavpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:50:42 INFO - Running setup.py (path:/tmp/pip-bLqgoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:50:42 INFO - Running setup.py (path:/tmp/pip-qlnHoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:50:42 INFO - Running setup.py (path:/tmp/pip-7eq6TX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:50:42 INFO - Running setup.py (path:/tmp/pip-2o7shm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:50:42 INFO - Running setup.py (path:/tmp/pip-ROF1d5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:50:42 INFO - Running setup.py (path:/tmp/pip-BVxNFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:50:42 INFO - Running setup.py (path:/tmp/pip-mHYHq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:50:42 INFO - Running setup.py (path:/tmp/pip-fPE__g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:50:42 INFO - Running setup.py (path:/tmp/pip-ZgeDrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:50:42 INFO - Running setup.py (path:/tmp/pip-5v5ByO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:50:42 INFO - Running setup.py (path:/tmp/pip-ZpwhJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:50:43 INFO - Running setup.py (path:/tmp/pip-dxD4PW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:50:43 INFO - Running setup.py (path:/tmp/pip-pOiWC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:50:43 INFO - Running setup.py (path:/tmp/pip-wInOT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:50:43 INFO - Running setup.py (path:/tmp/pip-Z93iFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:50:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:50:43 INFO - Running setup.py install for manifestparser 11:50:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:50:43 INFO - Running setup.py install for mozcrash 11:50:43 INFO - Running setup.py install for mozdebug 11:50:43 INFO - Running setup.py install for mozdevice 11:50:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:50:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:50:43 INFO - Running setup.py install for mozfile 11:50:43 INFO - Running setup.py install for mozhttpd 11:50:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:50:43 INFO - Running setup.py install for mozinfo 11:50:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:50:43 INFO - Running setup.py install for mozInstall 11:50:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:50:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:50:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:50:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:50:44 INFO - Running setup.py install for mozleak 11:50:44 INFO - Running setup.py install for mozlog 11:50:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:50:44 INFO - Running setup.py install for moznetwork 11:50:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:50:44 INFO - Running setup.py install for mozprocess 11:50:44 INFO - Running setup.py install for mozprofile 11:50:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:50:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:50:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:50:44 INFO - Running setup.py install for mozrunner 11:50:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:50:44 INFO - Running setup.py install for mozscreenshot 11:50:44 INFO - Running setup.py install for moztest 11:50:44 INFO - Running setup.py install for mozversion 11:50:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:50:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:50:45 INFO - Cleaning up... 11:50:45 INFO - Return code: 0 11:50:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:50:45 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')]} 11:50:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:45 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')]} 11:50:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:45 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 0x7fc0df433f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f27ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f24f60>, '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 0x1f25d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f261d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', '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 11:50:45 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 11:50:45 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 11:50:45 INFO - Using env: {'DISPLAY': ':0', 11:50:45 INFO - 'HOME': '/home/cltbld', 11:50:45 INFO - 'LANG': 'en_US.UTF-8', 11:50:45 INFO - 'LANGUAGE': 'en_US:en', 11:50:45 INFO - 'LOGNAME': 'cltbld', 11:50:45 INFO - 'MAIL': '/var/mail/cltbld', 11:50:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:45 INFO - 'MOZ_NO_REMOTE': '1', 11:50:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:45 INFO - 'NO_EM_RESTART': '1', 11:50:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:45 INFO - 'PWD': '/builds/slave/test', 11:50:45 INFO - 'SHELL': '/bin/bash', 11:50:45 INFO - 'SHLVL': '1', 11:50:45 INFO - 'TERM': 'linux', 11:50:45 INFO - 'TMOUT': '86400', 11:50:45 INFO - 'USER': 'cltbld', 11:50:45 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', 11:50:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:45 INFO - '_': '/tools/buildbot/bin/python'} 11:50:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:50:45 INFO - Running setup.py (path:/tmp/pip-dMVrby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:50:45 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)) 11:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:50:45 INFO - Running setup.py (path:/tmp/pip-EP0qvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:50:45 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)) 11:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:50:45 INFO - Running setup.py (path:/tmp/pip-ry8Ywi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:50:45 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)) 11:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:50:45 INFO - Running setup.py (path:/tmp/pip-18SFJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:50:45 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)) 11:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:50:45 INFO - Running setup.py (path:/tmp/pip-yXGAgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:50:45 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)) 11:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:50:45 INFO - Running setup.py (path:/tmp/pip-ctzCoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:50:45 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)) 11:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:50:45 INFO - Running setup.py (path:/tmp/pip-877hsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:50:45 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)) 11:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:50:45 INFO - Running setup.py (path:/tmp/pip-wAcSYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:50:45 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)) 11:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:50:45 INFO - Running setup.py (path:/tmp/pip-HHHYDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:50:45 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)) 11:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:50:45 INFO - Running setup.py (path:/tmp/pip-3IPlaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:50:46 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)) 11:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:50:46 INFO - Running setup.py (path:/tmp/pip-N8B7GM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:50:46 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)) 11:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:50:46 INFO - Running setup.py (path:/tmp/pip-nwp7EC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:50:46 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)) 11:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:50:46 INFO - Running setup.py (path:/tmp/pip-vrd0TV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:50:46 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)) 11:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:50:46 INFO - Running setup.py (path:/tmp/pip-f_GSyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:50:46 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)) 11:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:50:46 INFO - Running setup.py (path:/tmp/pip-TpNCeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:50:46 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)) 11:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:50:46 INFO - Running setup.py (path:/tmp/pip-ApnrJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:50:46 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)) 11:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:50:46 INFO - Running setup.py (path:/tmp/pip-mhK_Y_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:50:46 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)) 11:50:46 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)) 11:50:46 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)) 11:50:46 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)) 11:50:46 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)) 11:50:46 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)) 11:50:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:50:46 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 11:50:46 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 11:50:46 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 11:50:46 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 11:50:46 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 11:50:46 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 11:50:48 INFO - Downloading blessings-1.6.tar.gz 11:50:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:50:48 INFO - Installing collected packages: blessings 11:50:48 INFO - Running setup.py install for blessings 11:50:48 INFO - Successfully installed blessings 11:50:48 INFO - Cleaning up... 11:50:48 INFO - Return code: 0 11:50:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:50:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:50:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:50:48 INFO - Reading from file tmpfile_stdout 11:50:48 INFO - Current package versions: 11:50:48 INFO - argparse == 1.2.1 11:50:48 INFO - blessings == 1.6 11:50:48 INFO - blobuploader == 1.2.4 11:50:48 INFO - docopt == 0.6.1 11:50:48 INFO - functools32 == 3.2.3-2 11:50:48 INFO - jsonschema == 2.5.1 11:50:48 INFO - manifestparser == 1.1 11:50:48 INFO - mozInstall == 1.12 11:50:48 INFO - mozcrash == 0.17 11:50:48 INFO - mozdebug == 0.1 11:50:48 INFO - mozdevice == 0.48 11:50:48 INFO - mozfile == 1.2 11:50:48 INFO - mozhttpd == 0.7 11:50:48 INFO - mozinfo == 0.9 11:50:48 INFO - mozleak == 0.1 11:50:48 INFO - mozlog == 3.4 11:50:48 INFO - moznetwork == 0.27 11:50:48 INFO - mozprocess == 0.23 11:50:48 INFO - mozprofile == 0.28 11:50:48 INFO - mozrunner == 6.12 11:50:48 INFO - mozscreenshot == 0.1 11:50:48 INFO - mozsystemmonitor == 0.3 11:50:48 INFO - moztest == 0.7 11:50:48 INFO - mozversion == 1.4 11:50:48 INFO - psutil == 3.1.1 11:50:48 INFO - requests == 1.2.3 11:50:48 INFO - wsgiref == 0.1.2 11:50:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:50:48 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')]} 11:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:48 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')]} 11:50:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:48 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 0x7fc0df433f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f27ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f24f60>, '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 0x1f25d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f261d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', '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 11:50:48 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 11:50:48 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 11:50:48 INFO - Using env: {'DISPLAY': ':0', 11:50:48 INFO - 'HOME': '/home/cltbld', 11:50:48 INFO - 'LANG': 'en_US.UTF-8', 11:50:48 INFO - 'LANGUAGE': 'en_US:en', 11:50:48 INFO - 'LOGNAME': 'cltbld', 11:50:48 INFO - 'MAIL': '/var/mail/cltbld', 11:50:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:48 INFO - 'MOZ_NO_REMOTE': '1', 11:50:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:48 INFO - 'NO_EM_RESTART': '1', 11:50:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:48 INFO - 'PWD': '/builds/slave/test', 11:50:48 INFO - 'SHELL': '/bin/bash', 11:50:48 INFO - 'SHLVL': '1', 11:50:48 INFO - 'TERM': 'linux', 11:50:48 INFO - 'TMOUT': '86400', 11:50:48 INFO - 'USER': 'cltbld', 11:50:48 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', 11:50:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:48 INFO - '_': '/tools/buildbot/bin/python'} 11:50:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:49 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)) 11:50:49 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)) 11:50:49 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)) 11:50:49 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)) 11:50:49 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)) 11:50:49 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)) 11:50:49 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)) 11:50:49 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)) 11:50:49 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)) 11:50:49 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)) 11:50:49 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)) 11:50:49 INFO - Cleaning up... 11:50:49 INFO - Return code: 0 11:50:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:50:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:50:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0df433f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f27ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f24f60>, '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 0x1f25d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f261d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', '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 11:50:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:50:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:50:49 INFO - Using env: {'DISPLAY': ':0', 11:50:49 INFO - 'HOME': '/home/cltbld', 11:50:49 INFO - 'LANG': 'en_US.UTF-8', 11:50:49 INFO - 'LANGUAGE': 'en_US:en', 11:50:49 INFO - 'LOGNAME': 'cltbld', 11:50:49 INFO - 'MAIL': '/var/mail/cltbld', 11:50:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:49 INFO - 'MOZ_NO_REMOTE': '1', 11:50:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:49 INFO - 'NO_EM_RESTART': '1', 11:50:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:49 INFO - 'PWD': '/builds/slave/test', 11:50:49 INFO - 'SHELL': '/bin/bash', 11:50:49 INFO - 'SHLVL': '1', 11:50:49 INFO - 'TERM': 'linux', 11:50:49 INFO - 'TMOUT': '86400', 11:50:49 INFO - 'USER': 'cltbld', 11:50:49 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', 11:50:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:49 INFO - '_': '/tools/buildbot/bin/python'} 11:50:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:50:49 INFO - Cleaning up... 11:50:49 INFO - Return code: 0 11:50:49 INFO - Running post-action listener: _resource_record_post_action 11:50:49 INFO - Running post-action listener: _start_resource_monitoring 11:50:49 INFO - Starting resource monitoring. 11:50:49 INFO - [mozharness: 2016-09-19 18:50:49.395287Z] Finished create-virtualenv step (success) 11:50:49 INFO - [mozharness: 2016-09-19 18:50:49.395793Z] Running install step. 11:50:49 INFO - Running pre-action listener: _resource_record_pre_action 11:50:49 INFO - Running main action method: install 11:50:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:50:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:50:49 INFO - Reading from file tmpfile_stdout 11:50:49 INFO - Detecting whether we're running mozinstall >=1.0... 11:50:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:50:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:50:49 INFO - Reading from file tmpfile_stdout 11:50:49 INFO - Output received: 11:50:49 INFO - Usage: mozinstall [options] installer 11:50:49 INFO - Options: 11:50:49 INFO - -h, --help show this help message and exit 11:50:49 INFO - -d DEST, --destination=DEST 11:50:49 INFO - Directory to install application into. [default: 11:50:49 INFO - "/builds/slave/test"] 11:50:49 INFO - --app=APP Application being installed. [default: firefox] 11:50:49 INFO - mkdir: /builds/slave/test/build/application 11:50:49 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'] 11:50:49 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 11:51:05 INFO - Reading from file tmpfile_stdout 11:51:05 INFO - Output received: 11:51:05 INFO - /builds/slave/test/build/application/firefox/firefox 11:51:05 INFO - Running post-action listener: _resource_record_post_action 11:51:05 INFO - [mozharness: 2016-09-19 18:51:05.781864Z] Finished install step (success) 11:51:05 INFO - [mozharness: 2016-09-19 18:51:05.782111Z] Running run-tests step. 11:51:05 INFO - Running pre-action listener: _resource_record_pre_action 11:51:05 INFO - Running main action method: run_tests 11:51:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:51:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:51:05 INFO - Python 2.7.3 11:51:05 INFO - Return code: 0 11:51:05 INFO - grabbing minidump binary from tooltool 11:51:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:05 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 0x1f24f60>, '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 0x1f25d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f261d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:51:05 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 11:51:05 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 11:51:05 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 11:51:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:51:05 INFO - Return code: 0 11:51:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:51:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:51:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:51:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:51:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:51:05 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/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 11:51:05 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/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --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 11:51:05 INFO - Using env: {'DISPLAY': ':0', 11:51:05 INFO - 'HOME': '/home/cltbld', 11:51:05 INFO - 'LANG': 'en_US.UTF-8', 11:51:05 INFO - 'LANGUAGE': 'en_US:en', 11:51:05 INFO - 'LOGNAME': 'cltbld', 11:51:05 INFO - 'MAIL': '/var/mail/cltbld', 11:51:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:51:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:51:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:05 INFO - 'MOZ_NO_REMOTE': '1', 11:51:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:51:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:51:05 INFO - 'NO_EM_RESTART': '1', 11:51:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:51:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:05 INFO - 'PWD': '/builds/slave/test', 11:51:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:51:05 INFO - 'SHELL': '/bin/bash', 11:51:05 INFO - 'SHLVL': '1', 11:51:05 INFO - 'TERM': 'linux', 11:51:05 INFO - 'TMOUT': '86400', 11:51:05 INFO - 'USER': 'cltbld', 11:51:05 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477', 11:51:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:05 INFO - '_': '/tools/buildbot/bin/python'} 11:51:05 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/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 11:51:06 INFO - mozversion application_buildid: 20160919112617 11:51:06 INFO - mozversion application_changeset: 690b85efea256f02bb46f032397257aa7b99de3a 11:51:06 INFO - mozversion application_display_name: Nightly 11:51:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:51:06 INFO - mozversion application_name: Firefox 11:51:06 INFO - mozversion application_remotingname: firefox 11:51:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:51:06 INFO - mozversion application_vendor: Mozilla 11:51:06 INFO - mozversion application_version: 51.0a1 11:51:06 INFO - mozversion platform_buildid: 20160919112617 11:51:06 INFO - mozversion platform_changeset: 690b85efea256f02bb46f032397257aa7b99de3a 11:51:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:51:06 INFO - mozversion platform_version: 51.0a1 11:51:06 INFO - using testdate: 1474311066 11:51:06 INFO - actual date: 1474311066 11:51:06 INFO - starting webserver on 'localhost:52766' 11:51:06 INFO - SUITE-START | Running 2 tests 11:51:06 INFO - TEST-START | dromaeo_css 11:51:06 INFO - operating with platform_type : linux_ 11:51:06 INFO - Initialising browser for dromaeo_css test... 11:51:06 INFO - TEST-INFO | started process 4994 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8CNp5q/profile http://localhost:52766/getInfo.html) 11:51:10 INFO - PROCESS | 4994 | __metrics Screen width/height:1600/1200 11:51:10 INFO - PROCESS | 4994 | colorDepth:24 11:51:10 INFO - PROCESS | 4994 | Browser inner width/height: 1024/697 11:51:10 INFO - PROCESS | 4994 | __metrics 11:51:11 INFO - PROCESS | 4994 | 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" 11:51:11 INFO - PROCESS | 4994 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:51:11 INFO - PROCESS | 4994 | console.error: PushService: 11:51:11 INFO - PROCESS | 4994 | stateChangeProcessEnqueue: Error transitioning state 11:51:11 INFO - PROCESS | 4994 | UnknownError 11:51:12 INFO - TEST-INFO | 4994: exit 0 11:51:12 INFO - Browser initialized. 11:51:12 INFO - Running cycle 1/1 for dromaeo_css test... 11:51:12 INFO - TEST-INFO | started process 5076 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8CNp5q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:51:24 INFO - PROCESS | 5076 | RSS: Main: 174915584 11:51:24 INFO - PROCESS | 5076 | 11:51:31 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 11:51:36 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:51:54 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:51:56 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:51:58 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.11673 seconds. 11:52:12 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 11:52:15 INFO - PROCESS | 5076 | 1474311135818 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] 11:52:20 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 11:52:22 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:52:24 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.11674 seconds. 11:52:31 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 11:52:39 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:52:41 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 11:52:46 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.08337 seconds. 11:52:55 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 11:53:02 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01678 seconds. 11:53:03 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.0166 seconds. 11:53:08 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 11:53:13 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 11:53:18 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:53:20 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.06671 seconds. 11:53:34 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 11:53:36 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:53:45 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 11:53:55 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 11:53:57 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 11:53:59 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 11:54:01 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 11:54:07 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 11:54:12 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 11:54:25 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 11:54:27 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:54:28 INFO - PROCESS | 5076 | Cycle 1(1): loaded http://localhost:52766/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52766/tests/dromaeo/cssquery-ext.html) 11:54:30 INFO - PROCESS | 5076 | RSS: Main: 266780672 11:54:30 INFO - PROCESS | 5076 | 11:54:33 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 11:54:37 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:54:39 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 11:54:45 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 11:55:05 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 11:55:15 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 11:55:39 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:55:41 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:55:51 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 11:55:53 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 11:56:07 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 11:56:09 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 11:56:13 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 11:56:16 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 11:56:20 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 11:56:29 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 11:56:31 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:56:37 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 11:56:42 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.03335 seconds. 11:56:54 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 11:56:56 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 11:56:58 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:57:00 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:57:10 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 11:57:22 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 11:57:24 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 11:57:26 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:57:33 INFO - PROCESS | 5076 | Cycle 1(1): loaded http://localhost:52766/tests/dromaeo/cssquery-ext.html (next: http://localhost:52766/tests/dromaeo/cssquery-jquery.html) 11:57:33 INFO - PROCESS | 5076 | RSS: Main: 276062208 11:57:33 INFO - PROCESS | 5076 | 11:57:37 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 11:57:46 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 11:57:58 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 11:58:03 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 11:58:08 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 11:58:10 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 11:58:12 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 11:58:22 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 11:58:26 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 11:58:35 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 11:58:38 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 11:58:50 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 11:58:59 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 11:59:01 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 11:59:11 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 11:59:18 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 11:59:33 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 11:59:45 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 11:59:49 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 11:59:57 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:00:05 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:00:09 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:00:15 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:00:17 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:00:33 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:00:35 INFO - PROCESS | 5076 | Cycle 1(1): loaded http://localhost:52766/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52766/tests/dromaeo/cssquery-mootools.html) 12:00:36 INFO - PROCESS | 5076 | RSS: Main: 256348160 12:00:36 INFO - PROCESS | 5076 | 12:00:57 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:01:02 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:01:03 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:01:04 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 2.01673 seconds. 12:01:13 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:01:17 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:01:18 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 2.00006 seconds. 12:01:20 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:01:22 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:01:33 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:01:35 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:01:41 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:01:45 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:01:55 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:02:09 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:02:24 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:02:26 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:02:36 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:02:48 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:02:58 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 12:03:00 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:03:04 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:03:06 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:03:12 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:03:14 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:03:21 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:03:25 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:03:26 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:03:38 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:03:38 INFO - PROCESS | 5076 | Cycle 1(1): loaded http://localhost:52766/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52766/tests/dromaeo/cssquery-prototype.html) 12:03:39 INFO - PROCESS | 5076 | RSS: Main: 223895552 12:03:39 INFO - PROCESS | 5076 | 12:03:45 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:03:47 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:03:49 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:03:56 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00084 seconds. 12:04:12 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:04:15 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 12:04:36 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:04:38 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:04:44 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:04:46 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:04:49 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 12:04:52 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:04:54 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 12:05:10 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:05:11 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:05:13 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 12:05:17 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 12:05:23 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 12:05:24 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 2.05008 seconds. 12:05:29 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:05:33 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 12:05:35 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:05:43 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:05:45 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:05:46 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 2.00003 seconds. 12:05:47 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:06:03 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 12:06:05 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:06:12 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:06:14 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 12:06:24 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:06:36 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 12:06:41 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 2.01672 seconds. 12:06:41 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 2.01672 seconds. 12:06:42 INFO - PROCESS | 5076 | Cycle 1(1): loaded http://localhost:52766/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52766/tests/dromaeo/cssquery-yui.html) 12:06:43 INFO - PROCESS | 5076 | RSS: Main: 218570752 12:06:43 INFO - PROCESS | 5076 | 12:06:47 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:06:48 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 2.05009 seconds. 12:06:53 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:06:57 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:07:03 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00262 seconds. 12:07:09 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:07:13 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 12:07:15 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:07:17 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:07:19 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:07:21 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:07:39 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.03335 seconds. 12:07:52 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:07:54 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 12:07:58 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:08:00 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 12:08:07 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:08:18 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:08:20 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:08:31 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 12:08:32 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 12:08:38 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:08:40 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:08:42 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:08:57 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:09:03 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:09:04 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 2.00007 seconds. 12:09:05 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:09:13 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 12:09:31 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:09:35 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:09:41 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:09:43 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:09:45 INFO - PROCESS | 5076 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 12:09:45 INFO - PROCESS | 5076 | Cycle 1(1): loaded http://localhost:52766/tests/dromaeo/cssquery-yui.html (next: http://localhost:52766/tests/dromaeo/cssquery-dojo.html) 12:09:46 INFO - PROCESS | 5076 | RSS: Main: 307646464 12:09:46 INFO - PROCESS | 5076 | 12:09:46 INFO - PROCESS | 5076 | __start_tp_report 12:09:46 INFO - PROCESS | 5076 | _x_x_mozilla_page_load 12:09:46 INFO - PROCESS | 5076 | _x_x_mozilla_page_load_details 12:09:46 INFO - PROCESS | 5076 | |i|pagename|runs| 12:09:46 INFO - PROCESS | 5076 | |0;dojo.html;2591.248537924002;2619.2927909464433;2645.338665333666;2656.760891519763;2672.0247109804914;4620.884477888055;4699.201135806912;4738.076075165344;4798.880027999302;4842.201036828923;4717.528247175281;4732.195526760451;4735.005648813753;4743.549362810536;4755.358026666399;4267.295896177135;4303.182395344875;4378.584034516732;4400.933985990213;4454.933176002362;4354.433923589929;4359.149965756678;4364.323529852878;4391.670624703144;4440.888977775565;4309.676774241944;4397.296432570789;4412.624926881219;4415.249407600716;4482.955170448303;4388.078503514282;4503.887402814923;4528.50186479487;4533.161365147458;4540.7502587357685;4268.637165840907;4318.611324980767;4407.427034485529;4433.800478978454;4450.688451808374;4685.086408150412;4708.411448568929;4727.054589082198;4748.477667456612;4749.026449577863;5058.342415486;5058.988202359543;5062.417821950486;5147.459516750725;5217.869553261161;4930.531599498042;5127.48725127488;5140.946106048251;5143.382794064699;5174.197999310113;4633.258678611406;4719.952800471951;4747.8100875965265;4764.523547645208;4770.332153498513;5215.322008138994;5216.921746173811;5220.034293655686;5229.555487783505;5241.109011468059;4775.188218002879;4982.427020892571;4993.80024799004;5004.099262132851;5004.499550044967;4916.508349165127;4973.801047958041;5019.974900125476;5049.899002020013;5078.111330517145;5116.692998420107;5132.409772876093;5132.615053870974;5134.691918484903;5226.608004399685;5125.461826508163;5138.511841375063;5139.974300128475;5140.151874940668;5144.202648589475;4255.1915136124035;4303.82784688609;4313.741175529478;4350.260455722535;4361.934570981438;3777.962220377816;3800.410936304877;3803.8858834235016;3817.2938006468894;3844.154286057063;4455.554444555583;4455.6658250631335;4456.131054444352;4504.572065653758;4508.301213311942;5156.690598564098;5190.065788158092;5205.6616319939085;5205.765740541685;5272.340957380327;4294.656427485794;4350.347447882843;4384.276594361894;4402.889927751832;4435.379046933432;4582.518835522289;4604.88487787808;4607.216773148572;4648.209804333272;4669.766511674403;4843.2735089736825;4845.563899249085;4845.830395936126;4903.215485522299;4913.410390753133;4999.200127979506;5039.647224694236;5080.695158290514;5154.742262886915;5160.690358578498;4808.735519546459;4820.783064762024;4829.372181616367;4868.80524779019;4902.803887844446;5045.016221836707;5052.393712754493;5056.115179843439;5065.721385323843;5113.054084994288;4966.503349665005;5024.271480635361;5125.513076257749;5144.4855514449755;5144.903289601833;4891.437484689164;4913.533214344631;4927.852164435073;4983.028309479617;5051.797928082836;4966.056449274626;4967.130752118437;4970.65205435616;4972.975135124301;5039.319691841554;4768.475467698481;4834.299026641188;4858.78135483897;4868.902621947612;4954.157793175241;4929.679570827885;4935.481774413634;4973.751312434291;4987.551120399182;5046.899062018666;4862.489438609036;4871.318015477907;4873.439554451284;4894.632902532253;4898.706077635211 12:09:46 INFO - PROCESS | 5076 | |1;ext.html;548.3063924135968;563.1777604697141;566.1931747260154;566.580730259608;573.366430094745;27478.17565473034;28139.859300703494;30471.542926856087;40635.390469142985;42226.36660450096;39508.604913950854;39984.200315993665;40079.59920400795;40092;40328.39507407384;7546.056742907137;7702.8844567331535;7703.653335599884;7705.306522412981;7705.768826935201;7570;7655.885161722578;7656.961715191416;7658.540487570735;7660.731874384398;7646;7689.96185514954;7690.884636730453;7692.346150577179;7697.230276972314;7643.61781910905;7685.5004424712215;7687.385009199251;7693.153753087156;7697.0378702662165;7557.735479258255;7618.200088990687;7618.8476230475635;7622.771316860503;7626.694932202705;37141;37448;37488.25023499515;37492.81253593715;37496;2790.6232658591034;2916.7229113234207;2926.385459053601;2939.92650183745;3129.5149251865996;27816.443671126668;28584;28847.115288471196;29206.853965730035;30032.54951175734;3370.7471939604384;3487.2676737885076;3492.912677183065;3516.8241587920757;3537.133402316423;244520;245054;245059;245653;246535;32357.35285294328;50040.749796250784;50390;50393.748031260344;50415.49584504108;32647.347053059282;32750.67249327524;32832;32834.671653283636;32846.67153328484;13155.60533184006;13337.333133343489;13341.599752007456;13360.398782054637;13365.599032029055;10938.453077346101;10947.890521094847;11049.39228342449;11086.22396432242;11203.21577489568;7631.809204769814;7670.846583068307;7676.462647614613;7696.153423123452;7736.922630773733;2920.8247505149757;2979.0467050543616;2985.850707464618;3010.864511096962;3051.0862126668976;794.9960250198712;828.6229765456703;831.4595512916652;841.284907828341;847.4449235750593;25728.356791080372;26553.203403897915;27371.45257094847;27404.45191096167;27508;821.3962737388015;862.1895418306772;878.6792820620427;891.8706787515774;982.9557669904872;13357.93321033408;14266.144031358152;14434.639134021734;14552.345144468525;14586.4894728684;359256;363364;363868;364349;364489;46173.30740038902;46626;46643.30035049477;46660.76669616698;46726;1052.4579841381694;1070.983935240972;1108.5898217659517;1147.4205526209212;1160.802663547199;25059.624105638795;28263.858680706464;28425.857870710515;28551.286217843888;29225.85387073051;37950.240995180495;38880.22239555136;38899.41650875183;38909;39192.6080739189;273148;273566;273836;274959;276111;21432.249871255055;22028.33914982553;24613.630795537723;25061.498770024133;38372.808135959145;37975.43036854504;39101.02247443837;41201.587984119775;41267.7936610315;41416.37875431811;34645;35292.82353588318;35331.646683533865;35777.28445431129;36001.63998360088;34953.47569786404;35491.467627986116;35643;35664;35801.64198358087;35119.64880351266;35266.29467410689;36060.27879442344;36071;36139;31821.840890795396;33581.83209083939;33873.47569359434;34050.275977921214;34501.827490862386;25135.74864251407;25506;25652;25842;26057.909780042253 12:09:46 INFO - PROCESS | 5076 | |2;jquery.html;343.929494453637;358.92642008388265;369.48641388470026;372.11065553327546;389.9044734040159;12356.382180890952;12882.904953078987;14072.929635351822;15967;16503;16136.515904522854;16595.83404165958;16610.66778664426;16766.74849877253;16884.324627014936;4791.736454495001;5031.471695471978;5061.6962982221;5068.138416469209;5091.389033315989;2992.9102126936227;5011.047900898836;5041.193409054552;5072.4673909239555;5076.111680455924;3088.7528997680133;3781.0358358618514;4657.557532034468;5958.344582095966;6043.184170137041;5961.493273071794;6018.60879042863;6020;6026.216591843055;6028.065649824284;5126.487351264844;5277.366715994067;5279.683219006872;5280.841574752763;5311.1767676009895;19803.207871685114;19946.202151913905;20103.597928041505;20207.69688454674;20295.88372639504;1886.6981282994655;1971.5563998100313;2077.981788923432;2118.163325486424;2320.5938960681983;12960.546380876718;13210.801837972438;13344.2660653664;13570.864291357157;13686.863131368758;1667.0997661262904;1688.4681325382464;1711.8202588728254;1735.9305627774875;1749.361483058687;64892;65043;66326;67660.32339676589;67686.9999999995;9622.498150480786;9886.406815591088;9958.551865166084;10054.798904021878;10109.342892712097;13371.665708357174;13389.464421423034;13405.66485837862;13408;13431.3955871986;7173.820654483576;7334.9266507335315;7347.88978165328;7389.113306403266;7566;6731.360520750521;6911.03245545624;6923.653817309114;7281.672324745399;7673.501222420637;6073.939260607337;6114.2662880453745;6147.692615369214;6164.784232551929;6236.563440559117;1745.9127043647766;1750.824917508239;1778.9555261118317;1779.3238569343566;1794.883332583378;732.5018987088831;818.2472125644422;875.3347455933409;880.1594477274316;950.1116456113556;16452.341906323614;16586.502404927873;17202.827971720373;17293.65412691739;17631.118444077743;822.5887056471764;827.3381294964005;839.2656425627575;868.769776009358;920.3419555018194;9420.115976804667;10024;10026.298159128937;10046.095851373411;10228.897711022943;334692;337623;338366;347460;349514;62878.0568291476;63582.36417635857;65073.67463162654;66148;66517.66741166264;881.5944665453964;900.2213085680839;921.2445794448488;926;930.1675000874314;15209.6197595061;15235;15730.134842583768;16065.919670401574;16092.758608620647;33755.32489350248;34597.827010864785;34609.82695086609;35142;35682.82158589191;45023.774881125384;45667.77166114148;45712.771436142604;46076.30885536787;47200.7639961798;6435.163428754232;6663.733450661919;6811.795646130624;7020.087388639444;7548.886766698613;9249.861252081359;12135.514579417078;12228.144029917821;12305.200161989442;12610.532103671563;10078;10244.795104098026;10568.418736969541;10588.576456941637;11178.385188814693;4961.03259864323;7676;9403.905960940303;9841.261905356983;10343.63797267092;10271.178305735673;10481.318714283547;10693.144548436261;10815.24293299493;11065.72335691582;6888.931110688829;6998.16022077354;7087.50387472872;7129.928700712927;7383.409327253914;8148.3073938713505;9273.907260927304;9526.142647161789;9878.370068938544;10725.249232553959 12:09:46 INFO - PROCESS | 5076 | |3;mootools.html;1316.243160182895;1364.140591427401;1377.497213517066;1572.598987258249;1663.941762038328;6765.188177418717;7085.610291433969;7394.519356241841;7987.161348058457;7990;7340.9632951835165;7676;7676.654550545225;7880.211978802117;7897.170797066312;2204.0522575292616;2210.9447263818374;2232.9665055024107;2233.8659680419146;2235.966460503094;1833.5416145963509;1899.9810001900012;2096.947576310597;2121.5332626822074;2134.690469881866;2174.826013918883;2212.65703815908;2213.0483891926465;2213.690083388327;2216.7118274624277;2119.5760847830497;2135.2419890938727;2142.464383904024;2143.3998480425507;2145.914163433461;2075.5537559424647;2077.4494758888904;2080.9791902080938;2082.416923261489;2127.287358734826;7775.533467991939;7805.297523222937;7830.491018083806;7904.525728456253;7922.960385198095;4438.245498265271;4554.066416384666;4567.018091110427;4567.748773817439;4570.291604801228;2726.9318267043286;2844.459552684983;2907;2912.7961042727015;2927.9121626351243;851.4252879306443;878.6968495868979;893.8793262909554;925.9212966897821;927.8097989912119;10499.842502362471;10521.316114452613;10533.26267161299;10540.894591054068;10546.472676366151;3831.942520862189;4028.1742242840146;4114.732542384735;4188.560201178832;4213.747175169499;9009.864852027225;9025.142611451902;9029.819403611891;9041.954790226007;9101.863472047924;4603.861884143481;4620.976895115503;4621.214393553104;4621.468531118897;4623.630109591225;4168.979155104265;4218.156368726267;4224.1129362834145;4232.619064284229;4236.9788151059665;3648.4709717090873;3712.832922518493;3727.962720372816;3740.551133863954;3768.6042965488778;1097.5993762276712;1140.4525827602797;1158.3339579741682;1171.566520387462;1197.108154424959;746.7087835744064;749.3555542233674;766.9309762121436;779.2052106851094;787.8896954426385;4830.420349558076;4848.248521479177;4963.404391472975;5024.472430394829;5063.8480845574695;775.5889378629336;795.157133438557;842.1157784326433;848.5248260973874;862.4523427623504;3330.833458327074;3417.8974630761118;3450.430678937947;3452.8446219920165;3485.233248685336;9069.863952040725;9661.613535458644;9674.613015479303;9687.225021998223;9704.95147524272;9456.385334953206;9618.182454491307;9621.663241786504;9677.177439917687;9701.854472182922;7051.083359163276;7215.134183897861;7229.493935424575;7242.130944286614;7295.781126566211;4747.382840230886;4861.392325959255;4964.081644895706;4999.325091112799;5129.204973229155;8849.07084756117;8881.091502004607;8925.910740892508;8936.597853096497;8956.731298061068;8797.560121994004;9016.549172541478;9076.636934522545;9113.498757568397;9149.817003660022;6395.072714456284;6419.711113000019;6422.293547709654;6483.708233129426;6486.9351306488215;7055.294470553109;7105.076340075757;7118.644067796693;7125.964370178323;7129.429645628234;6555.770548030796;6571.57284776488;6592.472602191909;6789.796306110825;6803.149606299213;6451.741930322734;6459.935400646122;6472.546921715623;6480.513961452815;6530.249021362604;5823.82528524145;5881.73532191061;5882.647041177543;5883.499902508163;5886.941130588811;4402.977985110054;4520.751358675173;4570.3829982951875;4571;4608.28571571407;4373.978130109329;4402.330762981026;4484.170428470743;4548.613367863769;4628.699134556243 12:09:46 INFO - PROCESS | 5076 | |4;prototype.html;294.39501823752187;296.2549188790193;304.57511771079345;308.7236922953955;309.055834425829;4841.152798260299;4844.757762111898;4866.367372241612;4876.122692763285;4879.146149423846;4806.591439727627;4806.759662016902;4813.70030091875;4815.807367705296;4821.614270858323;3635.7636753611064;3747.475353450519;3750.899496282077;3768.792716400597;3797.3544497435364;3730.3213553900855;3745.8013435700586;3787.0910981364414;3799.25914446683;3843.0392401899526;3761.3417651910945;3772.4718539404366;3777.962220377802;3791.981040094796;3815.828287727045;3577.463380492921;3741.8690345837763;3765.644368027508;3780.6030366811638;3785.659290663826;3293.094399040283;3293.769436139471;3295.7528185385954;3296.5055241713694;3322.352141332441;4684.52437482192;4700.882477938045;4706.976465117653;4726.196546587088;4728.3143944127905;4293.742375457483;4304.117655880537;4305.289627211507;4308.82764689412;4314.072474417983;4947.975260123712;5018.573421259197;5038.445770965191;5057.241413787924;5065.822696205652;3571.8392672329805;3574.088607405095;3589.928201435983;3589.982050089759;3601.3877640801124;5258.395284542288;5259.5529380002745;5287.339082614673;5300.390455097636;5304.840854774363;3494.165563079353;3661.3226553087766;3686.2443199144113;3691.1325838427947;3702.4631428442726;3728.683061939711;3738.2149748553106;3742.438634204891;3763.6989040876665;3769.2273084018257;4292.969687275032;4295.2053870033515;4311.33174357975;4312.568743125662;4312.741235525878;4909.729964851896;5136.178211486219;5143.871403214949;5150.948490515122;5157.659008657776;1276.6361586947676;1298.8441387033618;1299.9860109114904;1300.9154404963647;1301.9544315949086;801.5110782422718;817.8568750468644;822.8272062866745;826.2646244842095;829.5022986208227;4299.527052024275;4419.801108950105;4421.602055814992;4421.750013739898;4441.666874984389;5089.745512724349;5131.230315452637;5212.478752124757;5236.266922630834;5253;4454.57681520255;4487.730736155776;4496.7077139985795;4508.07584445188;4536.410266665378;2676.4513274778624;2746.2722378569697;2764.4885696146275;2772.9029483967965;2778.3193117686296;4928.8767780805765;4936.494369217351;4936.851894443173;4938.530839570307;4947.084789313988;4990.426100998359;5338.786448542092;5353.483862584579;5370.248165256867;5378.327709036371;4855.393075865517;4866.95133048665;4887.731174785279;4900.754962251873;4903.828366007244;5146.459621739664;5155.407128180307;5221.738913054408;5228.529432350954;5263;4948.529889660476;5117.565006974449;5123.538881500683;5127.820526281562;5128.3076784633595;4840.661153719206;4887.9246565755475;4901.362822833009;4902.362692849907;4903.362562866804;3679.043448703303;3680.4111342186193;3697.6672099510097;3723.255348930171;3723.404255319136;4973.975130124326;5243.134882744304;5255.474452554714;5272.261883336413;5286.735663216747;5022.4977502249485;5044.0076169911945;5047.444781074006;5047.848564543069;5061.848144555669;5043.747812609281;5048.242763585492;5062.2029179640695;5079.314292570456;5079.314292570456;4706.929396058993;4874.756262186947;4877.3903262092235;4905.754712264444;4912.38595175603;4878.609596265047;5036.244563315386;5142.408623008252;5142.637201141775;5157.381114266312;4848.951510484992;4998.375203099613;5022.824201152942;5025.773840177274;5042.546170844642 12:09:46 INFO - PROCESS | 5076 | |5;yui.html;551.1787436719289;553.8338498450464;566.4505429733159;577.5956830218845;616.3743800042954;10970.177236707248;11831.29012259265;12148.3318417487;12161;12408.441620127094;12490.937545312283;12570.68573285666;12652.746945061093;12663.240205587648;12665.176763510366;603.4840211619069;738.199054026382;759.6695437484711;763.2596381509926;765.2997507280859;614.3764079459353;615.0958091605333;615.7075389190121;615.7106160104767;738.3613174603955;797.4776521378477;797.4935915693547;798.732424637747;799.2087833045301;800.087899794235;787.2717736094147;787.79123532264;787.8542469643135;788.1645455816824;788.4165717369134;467.76144166475;478.51191784380086;581.5609215042616;581.6510093943642;582.4786815799836;11483;11789.646310610695;11975.88024119765;11983.28100313984;12122;11259.54961801527;11972.281663100242;12117.87882121185;12163.635090947286;12414.56549020789;9506.049395060509;9549.331546791727;9572.904270957271;9804.705858824247;9812.264080193943;3089.7064778845975;3093.922651933697;3125.3124312651407;3151.2437015116207;3205.358928214343;65156.674216629086;65398.01902971507;66553;67128.66435667791;68348.6582567084;8937.508437035895;8984.505852178192;9055.230305424113;9304.813903721888;9349.953250233706;17153.914230428767;17299.913500432667;17381;17382.304707811545;17396.21717022737;6096.451319381277;6155.876882462326;6178.073289006685;6187.876242475125;6468.579542329733;3514.1741690702665;3537.1510837399355;3614.0964758810296;3639.9444161193487;3705.5182826232417;2801.369691819365;2923.956140657892;2934.9119526414243;2937.735603795669;2939.2064142681356;480.04710649360993;493.98271060512707;494.8391772673895;499.68020466901214;501.79426435161406;450.80840642727367;474.1560023158783;486.8077109541782;489.19527377463874;497.40311626048884;7107.502474826712;7123.89314160288;7137.82155446118;7270.563766174047;7408.555486670709;411.8434994701994;422.7949444519406;433.73542139295006;444.99431285295464;475.83657956297213;3301.8679252830098;3384.8984530464127;3488.38953183197;3555.964440355615;3557.4485954677066;84657;88485;91838;94083.52958235165;94288.52855735677;82487;82817.58591207005;83412.58293708615;83504;85225.57387213148;46369;50423;70618;73372.63313683504;76857.61571192108;6707.47220185289;6713.227978782307;6807.659617019228;6998.75504357345;7004.579725216503;42784;43159.78420107879;43342.566574333854;44316.33525497185;44600.55399445964;34607.653923461454;35428.114297142776;36257;61354.69322653358;62227;7792.37660987131;7826.960865195865;8083.151269116894;8156.8368632625825;8498.447600905922;8893.333000025154;8903.866442003504;9055.90944090577;9089.545522723969;9175.587098580447;8243.299319557906;8451.47873382797;8613.612387442456;8781.956090219763;8790.384673072824;8446.28206602421;8600.1399860016;8678.78303042429;8869.06874778139;8937;8817.118288171132;9029.864552031857;9070.138336857988;9709.114414996598;10052.095311422006;8668.306535477273;8709.608067636846;8789.604467799092;8990.651402289448;9288.535573221447;9245.399049061789;9394.15452609241;9674.613015479303;9694.127528522467;9696.9030309696 12:09:46 INFO - PROCESS | 5076 | __end_tp_report 12:09:46 INFO - PROCESS | 5076 | __start_cc_report 12:09:46 INFO - PROCESS | 5076 | _x_x_mozilla_cycle_collect,1107 12:09:46 INFO - PROCESS | 5076 | __end_cc_report 12:09:46 INFO - PROCESS | 5076 | __startTimestamp1474312186023__endTimestamp 12:09:46 INFO - PROCESS | 5076 | 12:09:46 INFO - PROCESS | 5076 | ------- Summary: start ------- 12:09:46 INFO - PROCESS | 5076 | Number of tests: 6 12:09:46 INFO - PROCESS | 5076 | 12:09:46 INFO - PROCESS | 5076 | [#0] dojo.html Cycles:165 Average:4717.33 Median:4845.56 stddev:495.50 (10.2%) stddev-sans-first:468.11 12:09:46 INFO - PROCESS | 5076 | Values: 2591.2 2619.3 2645.3 2656.8 2672.0 4620.9 4699.2 4738.1 4798.9 4842.2 4717.5 4732.2 4735.0 4743.5 4755.4 4267.3 4303.2 4378.6 4400.9 4454.9 4354.4 4359.1 4364.3 4391.7 4440.9 4309.7 4397.3 4412.6 4415.2 4483.0 4388.1 4503.9 4528.5 4533.2 4540.8 4268.6 4318.6 4407.4 4433.8 4450.7 4685.1 4708.4 4727.1 4748.5 4749.0 5058.3 5059.0 5062.4 5147.5 5217.9 4930.5 5127.5 5140.9 5143.4 5174.2 4633.3 4720.0 4747.8 4764.5 4770.3 5215.3 5216.9 5220.0 5229.6 5241.1 4775.2 4982.4 4993.8 5004.1 5004.5 4916.5 4973.8 5020.0 5049.9 5078.1 5116.7 5132.4 5132.6 5134.7 5226.6 5125.5 5138.5 5140.0 5140.2 5144.2 4255.2 4303.8 4313.7 4350.3 4361.9 3778.0 3800.4 3803.9 3817.3 3844.2 4455.6 4455.7 4456.1 4504.6 4508.3 5156.7 5190.1 5205.7 5205.8 5272.3 4294.7 4350.3 4384.3 4402.9 4435.4 4582.5 4604.9 4607.2 4648.2 4669.8 4843.3 4845.6 4845.8 4903.2 4913.4 4999.2 5039.6 5080.7 5154.7 5160.7 4808.7 4820.8 4829.4 4868.8 4902.8 5045.0 5052.4 5056.1 5065.7 5113.1 4966.5 5024.3 5125.5 5144.5 5144.9 4891.4 4913.5 4927.9 4983.0 5051.8 4966.1 4967.1 4970.7 4973.0 5039.3 4768.5 4834.3 4858.8 4868.9 4954.2 4929.7 4935.5 4973.8 4987.6 5046.9 4862.5 4871.3 4873.4 4894.6 4898.7 12:09:46 INFO - PROCESS | 5076 | 12:09:46 INFO - PROCESS | 5076 | [#1] ext.html Cycles:180 Average:43672.78 Median:35653.50 stddev:78557.32 (220.3%) stddev-sans-first:78710.96 12:09:46 INFO - PROCESS | 5076 | Values: 548.3 563.2 566.2 566.6 573.4 27478.2 28139.9 30471.5 40635.4 42226.4 39508.6 39984.2 40079.6 40092.0 40328.4 7546.1 7702.9 7703.7 7705.3 7705.8 7570.0 7655.9 7657.0 7658.5 7660.7 7646.0 7690.0 7690.9 7692.3 7697.2 7643.6 7685.5 7687.4 7693.2 7697.0 7557.7 7618.2 7618.8 7622.8 7626.7 37141.0 37448.0 37488.3 37492.8 37496.0 2790.6 2916.7 2926.4 2939.9 3129.5 27816.4 28584.0 28847.1 29206.9 30032.5 3370.7 3487.3 3492.9 3516.8 3537.1 244520.0 245054.0 245059.0 245653.0 246535.0 32357.4 50040.7 50390.0 50393.7 50415.5 32647.3 32750.7 32832.0 32834.7 32846.7 13155.6 13337.3 13341.6 13360.4 13365.6 10938.5 10947.9 11049.4 11086.2 11203.2 7631.8 7670.8 7676.5 7696.2 7736.9 2920.8 2979.0 2985.9 3010.9 3051.1 795.0 828.6 831.5 841.3 847.4 25728.4 26553.2 27371.5 27404.5 27508.0 821.4 862.2 878.7 891.9 983.0 13357.9 14266.1 14434.6 14552.3 14586.5 359256.0 363364.0 363868.0 364349.0 364489.0 46173.3 46626.0 46643.3 46660.8 46726.0 1052.5 1071.0 1108.6 1147.4 1160.8 25059.6 28263.9 28425.9 28551.3 29225.9 37950.2 38880.2 38899.4 38909.0 39192.6 273148.0 273566.0 273836.0 274959.0 276111.0 21432.2 22028.3 24613.6 25061.5 38372.8 37975.4 39101.0 41201.6 41267.8 41416.4 34645.0 35292.8 35331.6 35777.3 36001.6 34953.5 35491.5 35643.0 35664.0 35801.6 35119.6 35266.3 36060.3 36071.0 36139.0 31821.8 33581.8 33873.5 34050.3 34501.8 25135.7 25506.0 25652.0 25842.0 26057.9 12:09:46 INFO - PROCESS | 5076 | 12:09:46 INFO - PROCESS | 5076 | [#2] jquery.html Cycles:180 Average:22315.86 Median:45345.77 stddev:56324.02 (124.2%) stddev-sans-first:56457.87 12:09:46 INFO - PROCESS | 5076 | Values: 343.9 358.9 369.5 372.1 389.9 12356.4 12882.9 14072.9 15967.0 16503.0 16136.5 16595.8 16610.7 16766.7 16884.3 4791.7 5031.5 5061.7 5068.1 5091.4 2992.9 5011.0 5041.2 5072.5 5076.1 3088.8 3781.0 4657.6 5958.3 6043.2 5961.5 6018.6 6020.0 6026.2 6028.1 5126.5 5277.4 5279.7 5280.8 5311.2 19803.2 19946.2 20103.6 20207.7 20295.9 1886.7 1971.6 2078.0 2118.2 2320.6 12960.5 13210.8 13344.3 13570.9 13686.9 1667.1 1688.5 1711.8 1735.9 1749.4 64892.0 65043.0 66326.0 67660.3 67687.0 9622.5 9886.4 9958.6 10054.8 10109.3 13371.7 13389.5 13405.7 13408.0 13431.4 7173.8 7334.9 7347.9 7389.1 7566.0 6731.4 6911.0 6923.7 7281.7 7673.5 6073.9 6114.3 6147.7 6164.8 6236.6 1745.9 1750.8 1779.0 1779.3 1794.9 732.5 818.2 875.3 880.2 950.1 16452.3 16586.5 17202.8 17293.7 17631.1 822.6 827.3 839.3 868.8 920.3 9420.1 10024.0 10026.3 10046.1 10228.9 334692.0 337623.0 338366.0 347460.0 349514.0 62878.1 63582.4 65073.7 66148.0 66517.7 881.6 900.2 921.2 926.0 930.2 15209.6 15235.0 15730.1 16065.9 16092.8 33755.3 34597.8 34609.8 35142.0 35682.8 45023.8 45667.8 45712.8 46076.3 47200.8 6435.2 6663.7 6811.8 7020.1 7548.9 9249.9 12135.5 12228.1 12305.2 12610.5 10078.0 10244.8 10568.4 10588.6 11178.4 4961.0 7676.0 9403.9 9841.3 10343.6 10271.2 10481.3 10693.1 10815.2 11065.7 6888.9 6998.2 7087.5 7129.9 7383.4 8148.3 9273.9 9526.1 9878.4 10725.2 12:09:46 INFO - PROCESS | 5076 | 12:09:46 INFO - PROCESS | 5076 | [#3] mootools.html Cycles:180 Average:5043.91 Median:4981.70 stddev:2851.08 (57.2%) stddev-sans-first:2845.32 12:09:46 INFO - PROCESS | 5076 | Values: 1316.2 1364.1 1377.5 1572.6 1663.9 6765.2 7085.6 7394.5 7987.2 7990.0 7341.0 7676.0 7676.7 7880.2 7897.2 2204.1 2210.9 2233.0 2233.9 2236.0 1833.5 1900.0 2096.9 2121.5 2134.7 2174.8 2212.7 2213.0 2213.7 2216.7 2119.6 2135.2 2142.5 2143.4 2145.9 2075.6 2077.4 2081.0 2082.4 2127.3 7775.5 7805.3 7830.5 7904.5 7923.0 4438.2 4554.1 4567.0 4567.7 4570.3 2726.9 2844.5 2907.0 2912.8 2927.9 851.4 878.7 893.9 925.9 927.8 10499.8 10521.3 10533.3 10540.9 10546.5 3831.9 4028.2 4114.7 4188.6 4213.7 9009.9 9025.1 9029.8 9042.0 9101.9 4603.9 4621.0 4621.2 4621.5 4623.6 4169.0 4218.2 4224.1 4232.6 4237.0 3648.5 3712.8 3728.0 3740.6 3768.6 1097.6 1140.5 1158.3 1171.6 1197.1 746.7 749.4 766.9 779.2 787.9 4830.4 4848.2 4963.4 5024.5 5063.8 775.6 795.2 842.1 848.5 862.5 3330.8 3417.9 3450.4 3452.8 3485.2 9069.9 9661.6 9674.6 9687.2 9705.0 9456.4 9618.2 9621.7 9677.2 9701.9 7051.1 7215.1 7229.5 7242.1 7295.8 4747.4 4861.4 4964.1 4999.3 5129.2 8849.1 8881.1 8925.9 8936.6 8956.7 8797.6 9016.5 9076.6 9113.5 9149.8 6395.1 6419.7 6422.3 6483.7 6486.9 7055.3 7105.1 7118.6 7126.0 7129.4 6555.8 6571.6 6592.5 6789.8 6803.1 6451.7 6459.9 6472.5 6480.5 6530.2 5823.8 5881.7 5882.6 5883.5 5886.9 4403.0 4520.8 4570.4 4571.0 4608.3 4374.0 4402.3 4484.2 4548.6 4628.7 12:09:46 INFO - PROCESS | 5076 | 12:09:46 INFO - PROCESS | 5076 | [#4] prototype.html Cycles:180 Average:4199.46 Median:4831.14 stddev:1223.45 (25.3%) stddev-sans-first:1191.25 12:09:46 INFO - PROCESS | 5076 | Values: 294.4 296.3 304.6 308.7 309.1 4841.2 4844.8 4866.4 4876.1 4879.1 4806.6 4806.8 4813.7 4815.8 4821.6 3635.8 3747.5 3750.9 3768.8 3797.4 3730.3 3745.8 3787.1 3799.3 3843.0 3761.3 3772.5 3778.0 3792.0 3815.8 3577.5 3741.9 3765.6 3780.6 3785.7 3293.1 3293.8 3295.8 3296.5 3322.4 4684.5 4700.9 4707.0 4726.2 4728.3 4293.7 4304.1 4305.3 4308.8 4314.1 4948.0 5018.6 5038.4 5057.2 5065.8 3571.8 3574.1 3589.9 3590.0 3601.4 5258.4 5259.6 5287.3 5300.4 5304.8 3494.2 3661.3 3686.2 3691.1 3702.5 3728.7 3738.2 3742.4 3763.7 3769.2 4293.0 4295.2 4311.3 4312.6 4312.7 4909.7 5136.2 5143.9 5150.9 5157.7 1276.6 1298.8 1300.0 1300.9 1302.0 801.5 817.9 822.8 826.3 829.5 4299.5 4419.8 4421.6 4421.8 4441.7 5089.7 5131.2 5212.5 5236.3 5253.0 4454.6 4487.7 4496.7 4508.1 4536.4 2676.5 2746.3 2764.5 2772.9 2778.3 4928.9 4936.5 4936.9 4938.5 4947.1 4990.4 5338.8 5353.5 5370.2 5378.3 4855.4 4867.0 4887.7 4900.8 4903.8 5146.5 5155.4 5221.7 5228.5 5263.0 4948.5 5117.6 5123.5 5127.8 5128.3 4840.7 4887.9 4901.4 4902.4 4903.4 3679.0 3680.4 3697.7 3723.3 3723.4 4974.0 5243.1 5255.5 5272.3 5286.7 5022.5 5044.0 5047.4 5047.8 5061.8 5043.7 5048.2 5062.2 5079.3 5079.3 4706.9 4874.8 4877.4 4905.8 4912.4 4878.6 5036.2 5142.4 5142.6 5157.4 4849.0 4998.4 5022.8 5025.8 5042.5 12:09:46 INFO - PROCESS | 5076 | 12:09:46 INFO - PROCESS | 5076 | [#5] yui.html Cycles:180 Average:16091.11 Median:34207.44 stddev:23924.00 (69.9%) stddev-sans-first:23962.66 12:09:46 INFO - PROCESS | 5076 | Values: 551.2 553.8 566.5 577.6 616.4 10970.2 11831.3 12148.3 12161.0 12408.4 12490.9 12570.7 12652.7 12663.2 12665.2 603.5 738.2 759.7 763.3 765.3 614.4 615.1 615.7 615.7 738.4 797.5 797.5 798.7 799.2 800.1 787.3 787.8 787.9 788.2 788.4 467.8 478.5 581.6 581.7 582.5 11483.0 11789.6 11975.9 11983.3 12122.0 11259.5 11972.3 12117.9 12163.6 12414.6 9506.0 9549.3 9572.9 9804.7 9812.3 3089.7 3093.9 3125.3 3151.2 3205.4 65156.7 65398.0 66553.0 67128.7 68348.7 8937.5 8984.5 9055.2 9304.8 9350.0 17153.9 17299.9 17381.0 17382.3 17396.2 6096.5 6155.9 6178.1 6187.9 6468.6 3514.2 3537.2 3614.1 3639.9 3705.5 2801.4 2924.0 2934.9 2937.7 2939.2 480.0 494.0 494.8 499.7 501.8 450.8 474.2 486.8 489.2 497.4 7107.5 7123.9 7137.8 7270.6 7408.6 411.8 422.8 433.7 445.0 475.8 3301.9 3384.9 3488.4 3556.0 3557.4 84657.0 88485.0 91838.0 94083.5 94288.5 82487.0 82817.6 83412.6 83504.0 85225.6 46369.0 50423.0 70618.0 73372.6 76857.6 6707.5 6713.2 6807.7 6998.8 7004.6 42784.0 43159.8 43342.6 44316.3 44600.6 34607.7 35428.1 36257.0 61354.7 62227.0 7792.4 7827.0 8083.2 8156.8 8498.4 8893.3 8903.9 9055.9 9089.5 9175.6 8243.3 8451.5 8613.6 8782.0 8790.4 8446.3 8600.1 8678.8 8869.1 8937.0 8817.1 9029.9 9070.1 9709.1 10052.1 8668.3 8709.6 8789.6 8990.7 9288.5 9245.4 9394.2 9674.6 9694.1 9696.9 12:09:46 INFO - PROCESS | 5076 | -------- Summary: end -------- 12:09:46 INFO - PROCESS | 5076 | 12:09:46 INFO - TEST-INFO | 5076: exit 0 12:09:46 INFO - TEST-OK | dromaeo_css | took 1120919ms 12:09:46 INFO - TEST-START | kraken 12:09:46 INFO - operating with platform_type : linux_ 12:09:46 INFO - Initialising browser for kraken test... 12:09:46 INFO - TEST-INFO | started process 5379 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfslU8E/profile http://localhost:52766/getInfo.html) 12:09:51 INFO - PROCESS | 5379 | __metrics Screen width/height:1600/1200 12:09:51 INFO - PROCESS | 5379 | colorDepth:24 12:09:51 INFO - PROCESS | 5379 | Browser inner width/height: 1024/697 12:09:51 INFO - PROCESS | 5379 | __metrics 12:09:52 INFO - PROCESS | 5379 | 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" 12:09:52 INFO - PROCESS | 5379 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:09:52 INFO - PROCESS | 5379 | console.error: PushService: 12:09:52 INFO - PROCESS | 5379 | stateChangeProcessEnqueue: Error transitioning state 12:09:52 INFO - PROCESS | 5379 | UnknownError 12:09:53 INFO - TEST-INFO | 5379: exit 0 12:09:53 INFO - Browser initialized. 12:09:53 INFO - Running cycle 1/1 for kraken test... 12:09:53 INFO - TEST-INFO | started process 5462 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfslU8E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:10:05 INFO - PROCESS | 5462 | RSS: Main: 175415296 12:10:05 INFO - PROCESS | 5462 | 12:10:08 INFO - PROCESS | 5462 | Cycle 1(1): loaded http://localhost:52766/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52766/tests/kraken/driver.html?testName=audio-beat-detection) 12:10:09 INFO - PROCESS | 5462 | RSS: Main: 242315264 12:10:09 INFO - PROCESS | 5462 | 12:10:12 INFO - PROCESS | 5462 | Cycle 1(1): loaded http://localhost:52766/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52766/tests/kraken/driver.html?testName=audio-dft) 12:10:12 INFO - PROCESS | 5462 | RSS: Main: 218333184 12:10:12 INFO - PROCESS | 5462 | 12:10:16 INFO - PROCESS | 5462 | Cycle 1(1): loaded http://localhost:52766/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52766/tests/kraken/driver.html?testName=audio-fft) 12:10:16 INFO - PROCESS | 5462 | RSS: Main: 299970560 12:10:16 INFO - PROCESS | 5462 | 12:10:18 INFO - PROCESS | 5462 | Cycle 1(1): loaded http://localhost:52766/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52766/tests/kraken/driver.html?testName=audio-oscillator) 12:10:19 INFO - PROCESS | 5462 | RSS: Main: 209899520 12:10:19 INFO - PROCESS | 5462 | 12:10:21 INFO - PROCESS | 5462 | Cycle 1(1): loaded http://localhost:52766/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52766/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:10:22 INFO - PROCESS | 5462 | RSS: Main: 213745664 12:10:22 INFO - PROCESS | 5462 | 12:10:25 INFO - PROCESS | 5462 | Cycle 1(1): loaded http://localhost:52766/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52766/tests/kraken/driver.html?testName=imaging-darkroom) 12:10:25 INFO - PROCESS | 5462 | RSS: Main: 250310656 12:10:25 INFO - PROCESS | 5462 | 12:10:29 INFO - PROCESS | 5462 | Cycle 1(1): loaded http://localhost:52766/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52766/tests/kraken/driver.html?testName=imaging-desaturate) 12:10:30 INFO - PROCESS | 5462 | RSS: Main: 244809728 12:10:30 INFO - PROCESS | 5462 | 12:10:32 INFO - PROCESS | 5462 | Cycle 1(1): loaded http://localhost:52766/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52766/tests/kraken/driver.html?testName=json-parse-financial) 12:10:33 INFO - PROCESS | 5462 | RSS: Main: 247570432 12:10:33 INFO - PROCESS | 5462 | 12:10:35 INFO - PROCESS | 5462 | Cycle 1(1): loaded http://localhost:52766/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52766/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:10:35 INFO - PROCESS | 5462 | RSS: Main: 210726912 12:10:35 INFO - PROCESS | 5462 | 12:10:37 INFO - PROCESS | 5462 | Cycle 1(1): loaded http://localhost:52766/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52766/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:10:38 INFO - PROCESS | 5462 | RSS: Main: 219181056 12:10:38 INFO - PROCESS | 5462 | 12:10:40 INFO - PROCESS | 5462 | Cycle 1(1): loaded http://localhost:52766/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52766/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:10:41 INFO - PROCESS | 5462 | RSS: Main: 222658560 12:10:41 INFO - PROCESS | 5462 | 12:10:44 INFO - PROCESS | 5462 | Cycle 1(1): loaded http://localhost:52766/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52766/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:10:44 INFO - PROCESS | 5462 | RSS: Main: 223068160 12:10:44 INFO - PROCESS | 5462 | 12:10:47 INFO - PROCESS | 5462 | Cycle 1(1): loaded http://localhost:52766/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52766/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:10:48 INFO - PROCESS | 5462 | RSS: Main: 221741056 12:10:48 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | Cycle 1(1): loaded http://localhost:52766/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52766/tests/kraken/driver.html?testName=ai-astar) 12:10:50 INFO - PROCESS | 5462 | RSS: Main: 272818176 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | __start_tp_report 12:10:50 INFO - PROCESS | 5462 | _x_x_mozilla_page_load 12:10:50 INFO - PROCESS | 5462 | _x_x_mozilla_page_load_details 12:10:50 INFO - PROCESS | 5462 | |i|pagename|runs| 12:10:50 INFO - PROCESS | 5462 | |0;ai-astar;113;115;116;114;115;114;115;114;116;116 12:10:50 INFO - PROCESS | 5462 | |1;audio-beat-detection;152;125;123;165;136;125;130;128;166;130 12:10:50 INFO - PROCESS | 5462 | |2;audio-dft;160;165;163;162;163;160;161;162;160;161 12:10:50 INFO - PROCESS | 5462 | |3;audio-fft;77;76;76;111;77;78;79;77;117;76 12:10:50 INFO - PROCESS | 5462 | |4;audio-oscillator;84;85;111;84;84;84;118;84;84;125 12:10:50 INFO - PROCESS | 5462 | |5;imaging-gaussian-blur;117;117;116;118;116;117;116;117;117;117 12:10:50 INFO - PROCESS | 5462 | |6;imaging-darkroom;147;148;147;147;147;148;147;148;147;147 12:10:50 INFO - PROCESS | 5462 | |7;imaging-desaturate;95;95;95;94;95;95;95;96;95;95 12:10:50 INFO - PROCESS | 5462 | |8;json-parse-financial;63;63;62;63;65;65;64;64;64;64 12:10:50 INFO - PROCESS | 5462 | |9;json-stringify-tinderbox;65;66;65;66;67;66;66;65;66;70 12:10:50 INFO - PROCESS | 5462 | |10;stanford-crypto-aes;82;90;86;90;88;82;90;82;82;91 12:10:50 INFO - PROCESS | 5462 | |11;stanford-crypto-ccm;136;140;133;136;146;136;135;150;173;146 12:10:50 INFO - PROCESS | 5462 | |12;stanford-crypto-pbkdf2;174;173;170;177;171;173;170;174;171;170 12:10:50 INFO - PROCESS | 5462 | |13;stanford-crypto-sha256-iterative;70;72;69;71;68;68;70;68;71;67 12:10:50 INFO - PROCESS | 5462 | __end_tp_report 12:10:50 INFO - PROCESS | 5462 | __start_cc_report 12:10:50 INFO - PROCESS | 5462 | _x_x_mozilla_cycle_collect,1926 12:10:50 INFO - PROCESS | 5462 | __end_cc_report 12:10:50 INFO - PROCESS | 5462 | __startTimestamp1474312250424__endTimestamp 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | ------- Summary: start ------- 12:10:50 INFO - PROCESS | 5462 | Number of tests: 14 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | [#0] ai-astar Cycles:10 Average:114.80 Median:115.00 stddev:1.03 (0.9%) stddev-sans-first:0.87 12:10:50 INFO - PROCESS | 5462 | Values: 113.0 115.0 116.0 114.0 115.0 114.0 115.0 114.0 116.0 116.0 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:133.00 stddev:16.68 (12.5%) stddev-sans-first:16.90 12:10:50 INFO - PROCESS | 5462 | Values: 152.0 125.0 123.0 165.0 136.0 125.0 130.0 128.0 166.0 130.0 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.64 (1.0%) stddev-sans-first:1.62 12:10:50 INFO - PROCESS | 5462 | Values: 160.0 165.0 163.0 162.0 163.0 160.0 161.0 162.0 160.0 161.0 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | [#3] audio-fft Cycles:10 Average:84.40 Median:77.00 stddev:15.69 (20.4%) stddev-sans-first:16.41 12:10:50 INFO - PROCESS | 5462 | Values: 77.0 76.0 76.0 111.0 77.0 78.0 79.0 77.0 117.0 76.0 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:84.00 stddev:16.69 (19.9%) stddev-sans-first:17.28 12:10:50 INFO - PROCESS | 5462 | Values: 84.0 85.0 111.0 84.0 84.0 84.0 118.0 84.0 84.0 125.0 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 12:10:50 INFO - PROCESS | 5462 | Values: 117.0 117.0 116.0 118.0 116.0 117.0 116.0 117.0 117.0 117.0 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 12:10:50 INFO - PROCESS | 5462 | Values: 147.0 148.0 147.0 147.0 147.0 148.0 147.0 148.0 147.0 147.0 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 12:10:50 INFO - PROCESS | 5462 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 95.0 96.0 95.0 95.0 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | [#8] json-parse-financial Cycles:10 Average:63.70 Median:64.00 stddev:0.95 (1.5%) stddev-sans-first:0.97 12:10:50 INFO - PROCESS | 5462 | Values: 63.0 63.0 62.0 63.0 65.0 65.0 64.0 64.0 64.0 64.0 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.00 stddev:1.48 (2.2%) stddev-sans-first:1.50 12:10:50 INFO - PROCESS | 5462 | Values: 65.0 66.0 65.0 66.0 67.0 66.0 66.0 65.0 66.0 70.0 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:89.00 stddev:3.95 (4.4%) stddev-sans-first:3.87 12:10:50 INFO - PROCESS | 5462 | Values: 82.0 90.0 86.0 90.0 88.0 82.0 90.0 82.0 82.0 91.0 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | [#11] stanford-crypto-ccm Cycles:10 Average:143.10 Median:143.00 stddev:11.96 (8.4%) stddev-sans-first:12.40 12:10:50 INFO - PROCESS | 5462 | Values: 136.0 140.0 133.0 136.0 146.0 136.0 135.0 150.0 173.0 146.0 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:173.00 stddev:2.31 (1.3%) stddev-sans-first:2.37 12:10:50 INFO - PROCESS | 5462 | Values: 174.0 173.0 170.0 177.0 171.0 173.0 170.0 174.0 171.0 170.0 12:10:50 INFO - PROCESS | 5462 | 12:10:50 INFO - PROCESS | 5462 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:70.00 stddev:1.65 (2.4%) stddev-sans-first:1.73 12:10:50 INFO - PROCESS | 5462 | Values: 70.0 72.0 69.0 71.0 68.0 68.0 70.0 68.0 71.0 67.0 12:10:50 INFO - PROCESS | 5462 | -------- Summary: end -------- 12:10:50 INFO - PROCESS | 5462 | 12:10:51 INFO - TEST-INFO | 5462: exit 0 12:10:51 INFO - TEST-OK | kraken | took 64690ms 12:10:51 INFO - SUITE-END | took 1185s 12:10:52 INFO - Completed test suite (00:19:46) 12:10:52 INFO - javascript benchmark 12:10:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2591.248537924002, 2619.2927909464433, 2645.338665333666, 2656.760891519763, 2672.0247109804914, 4620.884477888055, 4699.201135806912, 4738.076075165344, 4798.880027999302, 4842.201036828923, 4717.528247175281, 4732.195526760451, 4735.005648813753, 4743.549362810536, 4755.358026666399, 4267.295896177135, 4303.182395344875, 4378.584034516732, 4400.933985990213, 4454.933176002362, 4354.433923589929, 4359.149965756678, 4364.323529852878, 4391.670624703144, 4440.888977775565, 4309.676774241944, 4397.296432570789, 4412.624926881219, 4415.249407600716, 4482.955170448303, 4388.078503514282, 4503.887402814923, 4528.50186479487, 4533.161365147458, 4540.7502587357685, 4268.637165840907, 4318.611324980767, 4407.427034485529, 4433.800478978454, 4450.688451808374, 4685.086408150412, 4708.411448568929, 4727.054589082198, 4748.477667456612, 4749.026449577863, 5058.342415486, 5058.988202359543, 5062.417821950486, 5147.459516750725, 5217.869553261161, 4930.531599498042, 5127.48725127488, 5140.946106048251, 5143.382794064699, 5174.197999310113, 4633.258678611406, 4719.952800471951, 4747.8100875965265, 4764.523547645208, 4770.332153498513, 5215.322008138994, 5216.921746173811, 5220.034293655686, 5229.555487783505, 5241.109011468059, 4775.188218002879, 4982.427020892571, 4993.80024799004, 5004.099262132851, 5004.499550044967, 4916.508349165127, 4973.801047958041, 5019.974900125476, 5049.899002020013, 5078.111330517145, 5116.692998420107, 5132.409772876093, 5132.615053870974, 5134.691918484903, 5226.608004399685, 5125.461826508163, 5138.511841375063, 5139.974300128475, 5140.151874940668, 5144.202648589475, 4255.1915136124035, 4303.82784688609, 4313.741175529478, 4350.260455722535, 4361.934570981438, 3777.962220377816, 3800.410936304877, 3803.8858834235016, 3817.2938006468894, 3844.154286057063, 4455.554444555583, 4455.6658250631335, 4456.131054444352, 4504.572065653758, 4508.301213311942, 5156.690598564098, 5190.065788158092, 5205.6616319939085, 5205.765740541685, 5272.340957380327, 4294.656427485794, 4350.347447882843, 4384.276594361894, 4402.889927751832, 4435.379046933432, 4582.518835522289, 4604.88487787808, 4607.216773148572, 4648.209804333272, 4669.766511674403, 4843.2735089736825, 4845.563899249085, 4845.830395936126, 4903.215485522299, 4913.410390753133, 4999.200127979506, 5039.647224694236, 5080.695158290514, 5154.742262886915, 5160.690358578498, 4808.735519546459, 4820.783064762024, 4829.372181616367, 4868.80524779019, 4902.803887844446, 5045.016221836707, 5052.393712754493, 5056.115179843439, 5065.721385323843, 5113.054084994288, 4966.503349665005, 5024.271480635361, 5125.513076257749, 5144.4855514449755, 5144.903289601833, 4891.437484689164, 4913.533214344631, 4927.852164435073, 4983.028309479617, 5051.797928082836, 4966.056449274626, 4967.130752118437, 4970.65205435616, 4972.975135124301, 5039.319691841554, 4768.475467698481, 4834.299026641188, 4858.78135483897, 4868.902621947612, 4954.157793175241, 4929.679570827885, 4935.481774413634, 4973.751312434291, 4987.551120399182, 5046.899062018666, 4862.489438609036, 4871.318015477907, 4873.439554451284, 4894.632902532253, 4898.706077635211], "value": 4685.427002401546, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [548.3063924135968, 563.1777604697141, 566.1931747260154, 566.580730259608, 573.366430094745, 27478.17565473034, 28139.859300703494, 30471.542926856087, 40635.390469142985, 42226.36660450096, 39508.604913950854, 39984.200315993665, 40079.59920400795, 40092.0, 40328.39507407384, 7546.056742907137, 7702.8844567331535, 7703.653335599884, 7705.306522412981, 7705.768826935201, 7570.0, 7655.885161722578, 7656.961715191416, 7658.540487570735, 7660.731874384398, 7646.0, 7689.96185514954, 7690.884636730453, 7692.346150577179, 7697.230276972314, 7643.61781910905, 7685.5004424712215, 7687.385009199251, 7693.153753087156, 7697.0378702662165, 7557.735479258255, 7618.200088990687, 7618.8476230475635, 7622.771316860503, 7626.694932202705, 37141.0, 37448.0, 37488.25023499515, 37492.81253593715, 37496.0, 2790.6232658591034, 2916.7229113234207, 2926.385459053601, 2939.92650183745, 3129.5149251865996, 27816.443671126668, 28584.0, 28847.115288471196, 29206.853965730035, 30032.54951175734, 3370.7471939604384, 3487.2676737885076, 3492.912677183065, 3516.8241587920757, 3537.133402316423, 244520.0, 245054.0, 245059.0, 245653.0, 246535.0, 32357.35285294328, 50040.749796250784, 50390.0, 50393.748031260344, 50415.49584504108, 32647.347053059282, 32750.67249327524, 32832.0, 32834.671653283636, 32846.67153328484, 13155.60533184006, 13337.333133343489, 13341.599752007456, 13360.398782054637, 13365.599032029055, 10938.453077346101, 10947.890521094847, 11049.39228342449, 11086.22396432242, 11203.21577489568, 7631.809204769814, 7670.846583068307, 7676.462647614613, 7696.153423123452, 7736.922630773733, 2920.8247505149757, 2979.0467050543616, 2985.850707464618, 3010.864511096962, 3051.0862126668976, 794.9960250198712, 828.6229765456703, 831.4595512916652, 841.284907828341, 847.4449235750593, 25728.356791080372, 26553.203403897915, 27371.45257094847, 27404.45191096167, 27508.0, 821.3962737388015, 862.1895418306772, 878.6792820620427, 891.8706787515774, 982.9557669904872, 13357.93321033408, 14266.144031358152, 14434.639134021734, 14552.345144468525, 14586.4894728684, 359256.0, 363364.0, 363868.0, 364349.0, 364489.0, 46173.30740038902, 46626.0, 46643.30035049477, 46660.76669616698, 46726.0, 1052.4579841381694, 1070.983935240972, 1108.5898217659517, 1147.4205526209212, 1160.802663547199, 25059.624105638795, 28263.858680706464, 28425.857870710515, 28551.286217843888, 29225.85387073051, 37950.240995180495, 38880.22239555136, 38899.41650875183, 38909.0, 39192.6080739189, 273148.0, 273566.0, 273836.0, 274959.0, 276111.0, 21432.249871255055, 22028.33914982553, 24613.630795537723, 25061.498770024133, 38372.808135959145, 37975.43036854504, 39101.02247443837, 41201.587984119775, 41267.7936610315, 41416.37875431811, 34645.0, 35292.82353588318, 35331.646683533865, 35777.28445431129, 36001.63998360088, 34953.47569786404, 35491.467627986116, 35643.0, 35664.0, 35801.64198358087, 35119.64880351266, 35266.29467410689, 36060.27879442344, 36071.0, 36139.0, 31821.840890795396, 33581.83209083939, 33873.47569359434, 34050.275977921214, 34501.827490862386, 25135.74864251407, 25506.0, 25652.0, 25842.0, 26057.909780042253], "value": 15908.214101237914, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.929494453637, 358.92642008388265, 369.48641388470026, 372.11065553327546, 389.9044734040159, 12356.382180890952, 12882.904953078987, 14072.929635351822, 15967.0, 16503.0, 16136.515904522854, 16595.83404165958, 16610.66778664426, 16766.74849877253, 16884.324627014936, 4791.736454495001, 5031.471695471978, 5061.6962982221, 5068.138416469209, 5091.389033315989, 2992.9102126936227, 5011.047900898836, 5041.193409054552, 5072.4673909239555, 5076.111680455924, 3088.7528997680133, 3781.0358358618514, 4657.557532034468, 5958.344582095966, 6043.184170137041, 5961.493273071794, 6018.60879042863, 6020.0, 6026.216591843055, 6028.065649824284, 5126.487351264844, 5277.366715994067, 5279.683219006872, 5280.841574752763, 5311.1767676009895, 19803.207871685114, 19946.202151913905, 20103.597928041505, 20207.69688454674, 20295.88372639504, 1886.6981282994655, 1971.5563998100313, 2077.981788923432, 2118.163325486424, 2320.5938960681983, 12960.546380876718, 13210.801837972438, 13344.2660653664, 13570.864291357157, 13686.863131368758, 1667.0997661262904, 1688.4681325382464, 1711.8202588728254, 1735.9305627774875, 1749.361483058687, 64892.0, 65043.0, 66326.0, 67660.32339676589, 67686.9999999995, 9622.498150480786, 9886.406815591088, 9958.551865166084, 10054.798904021878, 10109.342892712097, 13371.665708357174, 13389.464421423034, 13405.66485837862, 13408.0, 13431.3955871986, 7173.820654483576, 7334.9266507335315, 7347.88978165328, 7389.113306403266, 7566.0, 6731.360520750521, 6911.03245545624, 6923.653817309114, 7281.672324745399, 7673.501222420637, 6073.939260607337, 6114.2662880453745, 6147.692615369214, 6164.784232551929, 6236.563440559117, 1745.9127043647766, 1750.824917508239, 1778.9555261118317, 1779.3238569343566, 1794.883332583378, 732.5018987088831, 818.2472125644422, 875.3347455933409, 880.1594477274316, 950.1116456113556, 16452.341906323614, 16586.502404927873, 17202.827971720373, 17293.65412691739, 17631.118444077743, 822.5887056471764, 827.3381294964005, 839.2656425627575, 868.769776009358, 920.3419555018194, 9420.115976804667, 10024.0, 10026.298159128937, 10046.095851373411, 10228.897711022943, 334692.0, 337623.0, 338366.0, 347460.0, 349514.0, 62878.0568291476, 63582.36417635857, 65073.67463162654, 66148.0, 66517.66741166264, 881.5944665453964, 900.2213085680839, 921.2445794448488, 926.0, 930.1675000874314, 15209.6197595061, 15235.0, 15730.134842583768, 16065.919670401574, 16092.758608620647, 33755.32489350248, 34597.827010864785, 34609.82695086609, 35142.0, 35682.82158589191, 45023.774881125384, 45667.77166114148, 45712.771436142604, 46076.30885536787, 47200.7639961798, 6435.163428754232, 6663.733450661919, 6811.795646130624, 7020.087388639444, 7548.886766698613, 9249.861252081359, 12135.514579417078, 12228.144029917821, 12305.200161989442, 12610.532103671563, 10078.0, 10244.795104098026, 10568.418736969541, 10588.576456941637, 11178.385188814693, 4961.03259864323, 7676.0, 9403.905960940303, 9841.261905356983, 10343.63797267092, 10271.178305735673, 10481.318714283547, 10693.144548436261, 10815.24293299493, 11065.72335691582, 6888.931110688829, 6998.16022077354, 7087.50387472872, 7129.928700712927, 7383.409327253914, 8148.3073938713505, 9273.907260927304, 9526.142647161789, 9878.370068938544, 10725.249232553959], "value": 8081.6567694802925, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.243160182895, 1364.140591427401, 1377.497213517066, 1572.598987258249, 1663.941762038328, 6765.188177418717, 7085.610291433969, 7394.519356241841, 7987.161348058457, 7990.0, 7340.9632951835165, 7676.0, 7676.654550545225, 7880.211978802117, 7897.170797066312, 2204.0522575292616, 2210.9447263818374, 2232.9665055024107, 2233.8659680419146, 2235.966460503094, 1833.5416145963509, 1899.9810001900012, 2096.947576310597, 2121.5332626822074, 2134.690469881866, 2174.826013918883, 2212.65703815908, 2213.0483891926465, 2213.690083388327, 2216.7118274624277, 2119.5760847830497, 2135.2419890938727, 2142.464383904024, 2143.3998480425507, 2145.914163433461, 2075.5537559424647, 2077.4494758888904, 2080.9791902080938, 2082.416923261489, 2127.287358734826, 7775.533467991939, 7805.297523222937, 7830.491018083806, 7904.525728456253, 7922.960385198095, 4438.245498265271, 4554.066416384666, 4567.018091110427, 4567.748773817439, 4570.291604801228, 2726.9318267043286, 2844.459552684983, 2907.0, 2912.7961042727015, 2927.9121626351243, 851.4252879306443, 878.6968495868979, 893.8793262909554, 925.9212966897821, 927.8097989912119, 10499.842502362471, 10521.316114452613, 10533.26267161299, 10540.894591054068, 10546.472676366151, 3831.942520862189, 4028.1742242840146, 4114.732542384735, 4188.560201178832, 4213.747175169499, 9009.864852027225, 9025.142611451902, 9029.819403611891, 9041.954790226007, 9101.863472047924, 4603.861884143481, 4620.976895115503, 4621.214393553104, 4621.468531118897, 4623.630109591225, 4168.979155104265, 4218.156368726267, 4224.1129362834145, 4232.619064284229, 4236.9788151059665, 3648.4709717090873, 3712.832922518493, 3727.962720372816, 3740.551133863954, 3768.6042965488778, 1097.5993762276712, 1140.4525827602797, 1158.3339579741682, 1171.566520387462, 1197.108154424959, 746.7087835744064, 749.3555542233674, 766.9309762121436, 779.2052106851094, 787.8896954426385, 4830.420349558076, 4848.248521479177, 4963.404391472975, 5024.472430394829, 5063.8480845574695, 775.5889378629336, 795.157133438557, 842.1157784326433, 848.5248260973874, 862.4523427623504, 3330.833458327074, 3417.8974630761118, 3450.430678937947, 3452.8446219920165, 3485.233248685336, 9069.863952040725, 9661.613535458644, 9674.613015479303, 9687.225021998223, 9704.95147524272, 9456.385334953206, 9618.182454491307, 9621.663241786504, 9677.177439917687, 9701.854472182922, 7051.083359163276, 7215.134183897861, 7229.493935424575, 7242.130944286614, 7295.781126566211, 4747.382840230886, 4861.392325959255, 4964.081644895706, 4999.325091112799, 5129.204973229155, 8849.07084756117, 8881.091502004607, 8925.910740892508, 8936.597853096497, 8956.731298061068, 8797.560121994004, 9016.549172541478, 9076.636934522545, 9113.498757568397, 9149.817003660022, 6395.072714456284, 6419.711113000019, 6422.293547709654, 6483.708233129426, 6486.9351306488215, 7055.294470553109, 7105.076340075757, 7118.644067796693, 7125.964370178323, 7129.429645628234, 6555.770548030796, 6571.57284776488, 6592.472602191909, 6789.796306110825, 6803.149606299213, 6451.741930322734, 6459.935400646122, 6472.546921715623, 6480.513961452815, 6530.249021362604, 5823.82528524145, 5881.73532191061, 5882.647041177543, 5883.499902508163, 5886.941130588811, 4402.977985110054, 4520.751358675173, 4570.3829982951875, 4571.0, 4608.28571571407, 4373.978130109329, 4402.330762981026, 4484.170428470743, 4548.613367863769, 4628.699134556243], "value": 4048.7060409778396, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.39501823752187, 296.2549188790193, 304.57511771079345, 308.7236922953955, 309.055834425829, 4841.152798260299, 4844.757762111898, 4866.367372241612, 4876.122692763285, 4879.146149423846, 4806.591439727627, 4806.759662016902, 4813.70030091875, 4815.807367705296, 4821.614270858323, 3635.7636753611064, 3747.475353450519, 3750.899496282077, 3768.792716400597, 3797.3544497435364, 3730.3213553900855, 3745.8013435700586, 3787.0910981364414, 3799.25914446683, 3843.0392401899526, 3761.3417651910945, 3772.4718539404366, 3777.962220377802, 3791.981040094796, 3815.828287727045, 3577.463380492921, 3741.8690345837763, 3765.644368027508, 3780.6030366811638, 3785.659290663826, 3293.094399040283, 3293.769436139471, 3295.7528185385954, 3296.5055241713694, 3322.352141332441, 4684.52437482192, 4700.882477938045, 4706.976465117653, 4726.196546587088, 4728.3143944127905, 4293.742375457483, 4304.117655880537, 4305.289627211507, 4308.82764689412, 4314.072474417983, 4947.975260123712, 5018.573421259197, 5038.445770965191, 5057.241413787924, 5065.822696205652, 3571.8392672329805, 3574.088607405095, 3589.928201435983, 3589.982050089759, 3601.3877640801124, 5258.395284542288, 5259.5529380002745, 5287.339082614673, 5300.390455097636, 5304.840854774363, 3494.165563079353, 3661.3226553087766, 3686.2443199144113, 3691.1325838427947, 3702.4631428442726, 3728.683061939711, 3738.2149748553106, 3742.438634204891, 3763.6989040876665, 3769.2273084018257, 4292.969687275032, 4295.2053870033515, 4311.33174357975, 4312.568743125662, 4312.741235525878, 4909.729964851896, 5136.178211486219, 5143.871403214949, 5150.948490515122, 5157.659008657776, 1276.6361586947676, 1298.8441387033618, 1299.9860109114904, 1300.9154404963647, 1301.9544315949086, 801.5110782422718, 817.8568750468644, 822.8272062866745, 826.2646244842095, 829.5022986208227, 4299.527052024275, 4419.801108950105, 4421.602055814992, 4421.750013739898, 4441.666874984389, 5089.745512724349, 5131.230315452637, 5212.478752124757, 5236.266922630834, 5253.0, 4454.57681520255, 4487.730736155776, 4496.7077139985795, 4508.07584445188, 4536.410266665378, 2676.4513274778624, 2746.2722378569697, 2764.4885696146275, 2772.9029483967965, 2778.3193117686296, 4928.8767780805765, 4936.494369217351, 4936.851894443173, 4938.530839570307, 4947.084789313988, 4990.426100998359, 5338.786448542092, 5353.483862584579, 5370.248165256867, 5378.327709036371, 4855.393075865517, 4866.95133048665, 4887.731174785279, 4900.754962251873, 4903.828366007244, 5146.459621739664, 5155.407128180307, 5221.738913054408, 5228.529432350954, 5263.0, 4948.529889660476, 5117.565006974449, 5123.538881500683, 5127.820526281562, 5128.3076784633595, 4840.661153719206, 4887.9246565755475, 4901.362822833009, 4902.362692849907, 4903.362562866804, 3679.043448703303, 3680.4111342186193, 3697.6672099510097, 3723.255348930171, 3723.404255319136, 4973.975130124326, 5243.134882744304, 5255.474452554714, 5272.261883336413, 5286.735663216747, 5022.4977502249485, 5044.0076169911945, 5047.444781074006, 5047.848564543069, 5061.848144555669, 5043.747812609281, 5048.242763585492, 5062.2029179640695, 5079.314292570456, 5079.314292570456, 4706.929396058993, 4874.756262186947, 4877.3903262092235, 4905.754712264444, 4912.38595175603, 4878.609596265047, 5036.244563315386, 5142.408623008252, 5142.637201141775, 5157.381114266312, 4848.951510484992, 4998.375203099613, 5022.824201152942, 5025.773840177274, 5042.546170844642], "value": 3808.667384099044, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.1787436719289, 553.8338498450464, 566.4505429733159, 577.5956830218845, 616.3743800042954, 10970.177236707248, 11831.29012259265, 12148.3318417487, 12161.0, 12408.441620127094, 12490.937545312283, 12570.68573285666, 12652.746945061093, 12663.240205587648, 12665.176763510366, 603.4840211619069, 738.199054026382, 759.6695437484711, 763.2596381509926, 765.2997507280859, 614.3764079459353, 615.0958091605333, 615.7075389190121, 615.7106160104767, 738.3613174603955, 797.4776521378477, 797.4935915693547, 798.732424637747, 799.2087833045301, 800.087899794235, 787.2717736094147, 787.79123532264, 787.8542469643135, 788.1645455816824, 788.4165717369134, 467.76144166475, 478.51191784380086, 581.5609215042616, 581.6510093943642, 582.4786815799836, 11483.0, 11789.646310610695, 11975.88024119765, 11983.28100313984, 12122.0, 11259.54961801527, 11972.281663100242, 12117.87882121185, 12163.635090947286, 12414.56549020789, 9506.049395060509, 9549.331546791727, 9572.904270957271, 9804.705858824247, 9812.264080193943, 3089.7064778845975, 3093.922651933697, 3125.3124312651407, 3151.2437015116207, 3205.358928214343, 65156.674216629086, 65398.01902971507, 66553.0, 67128.66435667791, 68348.6582567084, 8937.508437035895, 8984.505852178192, 9055.230305424113, 9304.813903721888, 9349.953250233706, 17153.914230428767, 17299.913500432667, 17381.0, 17382.304707811545, 17396.21717022737, 6096.451319381277, 6155.876882462326, 6178.073289006685, 6187.876242475125, 6468.579542329733, 3514.1741690702665, 3537.1510837399355, 3614.0964758810296, 3639.9444161193487, 3705.5182826232417, 2801.369691819365, 2923.956140657892, 2934.9119526414243, 2937.735603795669, 2939.2064142681356, 480.04710649360993, 493.98271060512707, 494.8391772673895, 499.68020466901214, 501.79426435161406, 450.80840642727367, 474.1560023158783, 486.8077109541782, 489.19527377463874, 497.40311626048884, 7107.502474826712, 7123.89314160288, 7137.82155446118, 7270.563766174047, 7408.555486670709, 411.8434994701994, 422.7949444519406, 433.73542139295006, 444.99431285295464, 475.83657956297213, 3301.8679252830098, 3384.8984530464127, 3488.38953183197, 3555.964440355615, 3557.4485954677066, 84657.0, 88485.0, 91838.0, 94083.52958235165, 94288.52855735677, 82487.0, 82817.58591207005, 83412.58293708615, 83504.0, 85225.57387213148, 46369.0, 50423.0, 70618.0, 73372.63313683504, 76857.61571192108, 6707.47220185289, 6713.227978782307, 6807.659617019228, 6998.75504357345, 7004.579725216503, 42784.0, 43159.78420107879, 43342.566574333854, 44316.33525497185, 44600.55399445964, 34607.653923461454, 35428.114297142776, 36257.0, 61354.69322653358, 62227.0, 7792.37660987131, 7826.960865195865, 8083.151269116894, 8156.8368632625825, 8498.447600905922, 8893.333000025154, 8903.866442003504, 9055.90944090577, 9089.545522723969, 9175.587098580447, 8243.299319557906, 8451.47873382797, 8613.612387442456, 8781.956090219763, 8790.384673072824, 8446.28206602421, 8600.1399860016, 8678.78303042429, 8869.06874778139, 8937.0, 8817.118288171132, 9029.864552031857, 9070.138336857988, 9709.114414996598, 10052.095311422006, 8668.306535477273, 8709.608067636846, 8789.604467799092, 8990.651402289448, 9288.535573221447, 9245.399049061789, 9394.15452609241, 9674.613015479303, 9694.127528522467, 9696.9030309696], "value": 5796.047172565225, "unit": "score"}], "name": "dromaeo_css", "value": 6145.015988012854, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 116.0, 114.0, 115.0, 114.0, 115.0, 114.0, 116.0, 116.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 125.0, 123.0, 165.0, 136.0, 125.0, 130.0, 128.0, 166.0, 130.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 165.0, 163.0, 162.0, 163.0, 160.0, 161.0, 162.0, 160.0, 161.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 111.0, 77.0, 78.0, 79.0, 77.0, 117.0, 76.0], "value": 84.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 111.0, 84.0, 84.0, 84.0, 118.0, 84.0, 84.0, 125.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 118.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 147.0, 147.0, 148.0, 147.0, 148.0, 147.0, 147.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 63.0, 65.0, 65.0, 64.0, 64.0, 64.0, 64.0], "value": 63.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 67.0, 66.0, 66.0, 65.0, 66.0, 70.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 90.0, 86.0, 90.0, 88.0, 82.0, 90.0, 82.0, 82.0, 91.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 140.0, 133.0, 136.0, 146.0, 136.0, 135.0, 150.0, 173.0, 146.0], "value": 143.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 173.0, 170.0, 177.0, 171.0, 173.0, 170.0, 174.0, 171.0, 170.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 69.0, 71.0, 68.0, 68.0, 70.0, 68.0, 71.0, 67.0], "value": 69.4, "unit": "score"}], "name": "kraken", "value": 1553.3, "alertThreshold": 2.0}]} 12:10:52 INFO - Return code: 0 12:10:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:10:52 INFO - # TBPL SUCCESS # 12:10:52 INFO - Running post-action listener: _resource_record_post_action 12:10:52 INFO - [mozharness: 2016-09-19 19:10:52.220896Z] Finished run-tests step (success) 12:10:52 INFO - Running post-run listener: _resource_record_post_run 12:10:52 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 407490560; Read time: 8; Write time: 327932 12:10:52 INFO - TinderboxPrint: CPU usage
13.1% 12:10:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 12:10:52 INFO - TinderboxPrint: I/O write bytes / time
407,490,560 / 327,932 12:10:52 INFO - TinderboxPrint: CPU idle
8,337.2 (86.9%) 12:10:52 INFO - TinderboxPrint: CPU user
1,202.4 (12.5%) 12:10:52 INFO - TinderboxPrint: Swap in / out
0 / 0 12:10:52 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99762176; Read time: 0; Write time: 105300 12:10:52 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 290897920; Read time: 0; Write time: 217568 12:10:52 INFO - Running post-run listener: _upload_blobber_files 12:10:52 INFO - Blob upload gear active. 12:10:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:10:52 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 12:10:52 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'] 12:10:52 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 12:10:53 INFO - (blobuploader) - INFO - Open directory for files ... 12:10:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:10:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:10:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:10:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:10:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:10:54 INFO - (blobuploader) - INFO - Done attempting. 12:10:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:10:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:10:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:10:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:10:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:10:58 INFO - (blobuploader) - INFO - Done attempting. 12:10:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:10:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:10:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:10:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:10:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:10:59 INFO - (blobuploader) - INFO - Done attempting. 12:10:59 INFO - (blobuploader) - INFO - Iteration through files over. 12:10:59 INFO - Return code: 0 12:10:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:10:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:10:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f74f9f2843d4ff001c09e0194c8b0441a20a14082dee2e0d8ee49f7dac57121f543a1b5d9c4ce3b41722e1aa9b1b211177d772d2718872ea53b2f8079316e86c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ac4237b561495b2b9baf26dafbadace76f2a64c75542a7f1d5f44c69455145ea1ffd9d28ebad2344364f4e82ad40014bc6d6147f8ec50ad592a41042733c3344", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c203faac981f729dc50618b22594a5515fefd8f6b5e122f80332344d4a68d231babfa98fb15fb188c5bc6dc7a5dbf212d088b618050953535ef7ee5a74881b14"} 12:10:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:10:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:10:59 INFO - Contents: 12:10:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f74f9f2843d4ff001c09e0194c8b0441a20a14082dee2e0d8ee49f7dac57121f543a1b5d9c4ce3b41722e1aa9b1b211177d772d2718872ea53b2f8079316e86c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ac4237b561495b2b9baf26dafbadace76f2a64c75542a7f1d5f44c69455145ea1ffd9d28ebad2344364f4e82ad40014bc6d6147f8ec50ad592a41042733c3344", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c203faac981f729dc50618b22594a5515fefd8f6b5e122f80332344d4a68d231babfa98fb15fb188c5bc6dc7a5dbf212d088b618050953535ef7ee5a74881b14"} 12:10:59 INFO - Running post-run listener: copy_logs_to_upload_dir 12:10:59 INFO - Copying logs to upload dir... 12:10:59 INFO - mkdir: /builds/slave/test/build/upload/logs 12:10:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.574348 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-09-19 12:10:58.921273) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 12:10:58.925112) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477 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/f74f9f2843d4ff001c09e0194c8b0441a20a14082dee2e0d8ee49f7dac57121f543a1b5d9c4ce3b41722e1aa9b1b211177d772d2718872ea53b2f8079316e86c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ac4237b561495b2b9baf26dafbadace76f2a64c75542a7f1d5f44c69455145ea1ffd9d28ebad2344364f4e82ad40014bc6d6147f8ec50ad592a41042733c3344", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c203faac981f729dc50618b22594a5515fefd8f6b5e122f80332344d4a68d231babfa98fb15fb188c5bc6dc7a5dbf212d088b618050953535ef7ee5a74881b14"} build_url:https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011470 build_url: 'https://queue.taskcluster.net/v1/task/NN-1mPlWSuGQ-5YzaSs3UQ/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/f74f9f2843d4ff001c09e0194c8b0441a20a14082dee2e0d8ee49f7dac57121f543a1b5d9c4ce3b41722e1aa9b1b211177d772d2718872ea53b2f8079316e86c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ac4237b561495b2b9baf26dafbadace76f2a64c75542a7f1d5f44c69455145ea1ffd9d28ebad2344364f4e82ad40014bc6d6147f8ec50ad592a41042733c3344", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c203faac981f729dc50618b22594a5515fefd8f6b5e122f80332344d4a68d231babfa98fb15fb188c5bc6dc7a5dbf212d088b618050953535ef7ee5a74881b14"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 12:10:58.956798) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 12:10:58.957157) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474310483.887963-1843022477 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004464 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 12:10:59.038938) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 12:10:59.039279) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 12:10:59.040170) ========= ========= Total master_lag: 0.18 =========