builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-003 starttime: 1474029074.02 results: success (0) buildid: 20160916035715 builduid: 9dc542d548d04d57815e5d7f72630a1b revision: f1dbeb5dee22cabc56b2cf25d2eab1435abbf131 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 05:31:14.021946) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 05:31:14.022363) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 05:31:14.022634) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 05:31:14.141403) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 05:31:14.141663) ========= 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=7de08804244eb36560b44971000001c3-1474028783.291941-547746672 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-16 05:31:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 88.6M=0s 2016-09-16 05:31:14 (88.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109052 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 05:31:14.286506) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 05:31:14.286846) ========= 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=7de08804244eb36560b44971000001c3-1474028783.291941-547746672 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018702 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 05:31:14.336563) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 05:31:14.336935) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f1dbeb5dee22cabc56b2cf25d2eab1435abbf131 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f1dbeb5dee22cabc56b2cf25d2eab1435abbf131 --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=7de08804244eb36560b44971000001c3-1474028783.291941-547746672 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-16 05:31:14,749 truncating revision to first 12 chars 2016-09-16 05:31:14,749 Setting DEBUG logging. 2016-09-16 05:31:14,749 attempt 1/10 2016-09-16 05:31:14,749 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f1dbeb5dee22?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-16 05:31:15,041 unpacking tar archive at: mozilla-central-f1dbeb5dee22/testing/mozharness/ program finished with exit code 0 elapsedTime=0.626638 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 05:31:14.979592) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 05:31:14.979867) ========= script_repo_revision: f1dbeb5dee22cabc56b2cf25d2eab1435abbf131 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 05:31:14.983275) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 05:31:14.983543) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 05:31:14.993440) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-09-16 05:31:14.993710) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=7de08804244eb36560b44971000001c3-1474028783.291941-547746672 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:31:15 INFO - MultiFileLogger online at 20160916 05:31:15 in /builds/slave/test 05:31:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 05:31:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:31:15 INFO - {'append_to_log': False, 05:31:15 INFO - 'base_work_dir': '/builds/slave/test', 05:31:15 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:31:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:31:15 INFO - 'branch': 'Firefox-Non-PGO', 05:31:15 INFO - 'buildbot_json_path': 'buildprops.json', 05:31:15 INFO - 'config_files': ('talos/linux_config.py',), 05:31:15 INFO - 'default_actions': ('clobber', 05:31:15 INFO - 'read-buildbot-config', 05:31:15 INFO - 'download-and-extract', 05:31:15 INFO - 'populate-webroot', 05:31:15 INFO - 'create-virtualenv', 05:31:15 INFO - 'install', 05:31:15 INFO - 'run-tests'), 05:31:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:31:15 INFO - 'download_minidump_stackwalk': True, 05:31:15 INFO - 'download_symbols': 'ondemand', 05:31:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:31:15 INFO - 'tooltool.py': '/tools/tooltool.py', 05:31:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:31:15 INFO - '/tools/misc-python/virtualenv.py')}, 05:31:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:31:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:31:15 INFO - 'installer_path': 'installer.exe', 05:31:15 INFO - 'log_level': 'info', 05:31:15 INFO - 'log_name': 'talos', 05:31:15 INFO - 'log_to_console': True, 05:31:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:31:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:31:15 INFO - 'opt_config_files': (), 05:31:15 INFO - 'pip_index': False, 05:31:15 INFO - 'sps_profile': False, 05:31:15 INFO - 'sps_profile_interval': 0, 05:31:15 INFO - 'suite': 'dromaeojs', 05:31:15 INFO - 'system_bits': '32', 05:31:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:31:15 INFO - 'title': 'talos-linux64-ix-003', 05:31:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:31:15 INFO - 'use_talos_json': True, 05:31:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:31:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:31:15 INFO - 'work_dir': 'build'} 05:31:15 INFO - [mozharness: 2016-09-16 12:31:15.508059Z] Running clobber step. 05:31:15 INFO - Running pre-action listener: _resource_record_pre_action 05:31:15 INFO - Running main action method: clobber 05:31:15 INFO - rmtree: /builds/slave/test/build 05:31:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:31:16 INFO - Running post-action listener: _resource_record_post_action 05:31:16 INFO - [mozharness: 2016-09-16 12:31:16.491906Z] Finished clobber step (success) 05:31:16 INFO - [mozharness: 2016-09-16 12:31:16.492004Z] Running read-buildbot-config step. 05:31:16 INFO - Running pre-action listener: _resource_record_pre_action 05:31:16 INFO - Running main action method: read_buildbot_config 05:31:16 INFO - Using buildbot properties: 05:31:16 INFO - { 05:31:16 INFO - "project": "", 05:31:16 INFO - "product": "firefox", 05:31:16 INFO - "script_repo_revision": "production", 05:31:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:31:16 INFO - "repository": "", 05:31:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 05:31:16 INFO - "buildid": "20160916035715", 05:31:16 INFO - "pgo_build": "False", 05:31:16 INFO - "basedir": "/builds/slave/test", 05:31:16 INFO - "buildnumber": 210, 05:31:16 INFO - "slavename": "talos-linux64-ix-003", 05:31:16 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:31:16 INFO - "platform": "ubuntu64_hw", 05:31:16 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 05:31:16 INFO - "branch": "mozilla-central", 05:31:16 INFO - "repo_path": "mozilla-central", 05:31:16 INFO - "revision": "f1dbeb5dee22cabc56b2cf25d2eab1435abbf131", 05:31:16 INFO - "stage_platform": "linux64", 05:31:16 INFO - "builduid": "9dc542d548d04d57815e5d7f72630a1b", 05:31:16 INFO - "slavebuilddir": "test" 05:31:16 INFO - } 05:31:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 05:31:16 INFO - Running post-action listener: _resource_record_post_action 05:31:16 INFO - [mozharness: 2016-09-16 12:31:16.494461Z] Finished read-buildbot-config step (success) 05:31:16 INFO - [mozharness: 2016-09-16 12:31:16.494551Z] Running download-and-extract step. 05:31:16 INFO - Running pre-action listener: _resource_record_pre_action 05:31:16 INFO - Running main action method: download_and_extract 05:31:16 INFO - mkdir: /builds/slave/test/build/tests 05:31:16 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')]} 05:31:16 INFO - trying https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:31:16 INFO - Downloading https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/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 05:31:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/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 05:31:17 INFO - Downloaded 1361 bytes. 05:31:17 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:31:17 INFO - Using the following test package requirements: 05:31:17 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:17 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:31:17 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:31:17 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:17 INFO - u'jsshell-linux-x86_64.zip'], 05:31:17 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:31:17 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:17 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:31:17 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:31:17 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:31:17 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:31:17 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:31:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:31:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:31:17 INFO - Fetch https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:31:17 INFO - Expected file size: 20364106 05:31:21 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 05:31:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:31:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:31:21 INFO - Fetch https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:31:22 INFO - Expected file size: 20364106 05:31:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:31:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:31:27 INFO - Fetch https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 05:31:28 INFO - Expected file size: 14414048 05:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:28 INFO - trying https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:31:28 INFO - Downloading https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/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 05:31:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/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 05:31:30 INFO - Downloaded 55586908 bytes. 05:31:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:31:30 INFO - mkdir: /builds/slave/test/properties 05:31:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:31:30 INFO - Writing to file /builds/slave/test/properties/build_url 05:31:30 INFO - Contents: 05:31:30 INFO - build_url:https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:31:30 INFO - Running post-action listener: _resource_record_post_action 05:31:30 INFO - Running post-action listener: set_extra_try_arguments 05:31:30 INFO - [mozharness: 2016-09-16 12:31:30.750406Z] Finished download-and-extract step (success) 05:31:30 INFO - [mozharness: 2016-09-16 12:31:30.750634Z] Running populate-webroot step. 05:31:30 INFO - Running pre-action listener: _resource_record_pre_action 05:31:30 INFO - Running main action method: populate_webroot 05:31:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:31:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:31:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:31:30 INFO - '--tppagecycles', 05:31:30 INFO - '7'], 05:31:30 INFO - 'tests': ['tsvgm']}}, 05:31:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:31:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:31:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:31:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:31:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:31:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:31:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:30 INFO - 'talos_options': ['--disable-e10s'], 05:31:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:30 INFO - 'talos_options': ['--disable-e10s'], 05:31:30 INFO - 'tests': ['damp', 'tps']}, 05:31:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:30 INFO - 'tests': ['damp', 'tps']}, 05:31:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:31:30 INFO - 'tests': ['dromaeo_dom']}, 05:31:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:31:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:31:30 INFO - 'tests': ['basic_compositor_video']}, 05:31:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:31:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:31:30 INFO - 'tests': ['a11yr', 05:31:30 INFO - 'ts_paint', 05:31:30 INFO - 'tpaint', 05:31:30 INFO - 'sessionrestore', 05:31:30 INFO - 'sessionrestore_no_auto_restore', 05:31:30 INFO - 'tabpaint']}, 05:31:30 INFO - 'other-e10s': {'tests': ['a11yr', 05:31:30 INFO - 'ts_paint', 05:31:30 INFO - 'tpaint', 05:31:30 INFO - 'sessionrestore', 05:31:30 INFO - 'sessionrestore_no_auto_restore', 05:31:30 INFO - 'tabpaint']}, 05:31:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:31:30 INFO - 'ts_paint', 05:31:30 INFO - 'tpaint', 05:31:30 INFO - 'sessionrestore', 05:31:30 INFO - 'sessionrestore_no_auto_restore', 05:31:30 INFO - 'tabpaint']}, 05:31:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:31:30 INFO - 'ts_paint', 05:31:30 INFO - 'tpaint', 05:31:30 INFO - 'sessionrestore', 05:31:30 INFO - 'sessionrestore_no_auto_restore', 05:31:30 INFO - 'tabpaint']}, 05:31:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:31:30 INFO - 'tests': ['a11yr', 05:31:30 INFO - 'ts_paint', 05:31:30 INFO - 'tpaint', 05:31:30 INFO - 'sessionrestore', 05:31:30 INFO - 'sessionrestore_no_auto_restore', 05:31:30 INFO - 'tabpaint']}, 05:31:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:31:30 INFO - 'tests': ['a11yr', 05:31:30 INFO - 'ts_paint', 05:31:30 INFO - 'tpaint', 05:31:30 INFO - 'sessionrestore', 05:31:30 INFO - 'sessionrestore_no_auto_restore', 05:31:30 INFO - 'tabpaint']}, 05:31:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:31:30 INFO - 'tests': ['tsvgx', 05:31:30 INFO - 'tsvgr_opacity', 05:31:30 INFO - 'tart', 05:31:30 INFO - 'tscrollx', 05:31:30 INFO - 'cart']}, 05:31:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:31:30 INFO - 'tsvgr_opacity', 05:31:30 INFO - 'tart', 05:31:30 INFO - 'tscrollx', 05:31:30 INFO - 'cart']}, 05:31:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:30 INFO - 'talos_options': ['--disable-e10s'], 05:31:30 INFO - 'tests': ['tp5o']}, 05:31:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:30 INFO - 'tests': ['tp5o']}, 05:31:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:30 INFO - 'talos_options': ['--disable-e10s', 05:31:30 INFO - '--xperf_path', 05:31:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:30 INFO - 'tests': ['tp5n']}, 05:31:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:30 INFO - 'talos_options': ['--xperf_path', 05:31:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:30 INFO - 'tests': ['tp5n']}}, 05:31:30 INFO - 'talos.zip': {'path': '', 05:31:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:31:30 INFO - Running post-action listener: _resource_record_post_action 05:31:30 INFO - [mozharness: 2016-09-16 12:31:30.767571Z] Finished populate-webroot step (success) 05:31:30 INFO - [mozharness: 2016-09-16 12:31:30.767659Z] Running create-virtualenv step. 05:31:30 INFO - Running pre-action listener: _resource_record_pre_action 05:31:30 INFO - Running main action method: create_virtualenv 05:31:30 INFO - Creating virtualenv /builds/slave/test/build/venv 05:31:30 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 05:31:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:31:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:31:30 INFO - Using real prefix '/usr' 05:31:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:31:31 INFO - Installing distribute.............................................................................................................................................................................................done. 05:31:33 INFO - Installing pip.................done. 05:31:33 INFO - Return code: 0 05:31:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:31: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')]} 05:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cfc61ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2867f60>, '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 0x2868d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28691d0>, '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': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', '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 05:31: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', 'pip>=1.5'] in /builds/slave/test/build 05:31: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 pip>=1.5 05:31:33 INFO - Using env: {'DISPLAY': ':0', 05:31:33 INFO - 'HOME': '/home/cltbld', 05:31:33 INFO - 'LANG': 'en_US.UTF-8', 05:31:33 INFO - 'LANGUAGE': 'en_US:en', 05:31:33 INFO - 'LOGNAME': 'cltbld', 05:31:33 INFO - 'MAIL': '/var/mail/cltbld', 05:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:33 INFO - 'MOZ_NO_REMOTE': '1', 05:31:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:33 INFO - 'NO_EM_RESTART': '1', 05:31:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:33 INFO - 'PWD': '/builds/slave/test', 05:31:33 INFO - 'SHELL': '/bin/bash', 05:31:33 INFO - 'SHLVL': '1', 05:31:33 INFO - 'TERM': 'linux', 05:31:33 INFO - 'TMOUT': '86400', 05:31:33 INFO - 'USER': 'cltbld', 05:31:33 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', 05:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:33 INFO - '_': '/tools/buildbot/bin/python'} 05:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:33 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 05:31:33 INFO - Cleaning up... 05:31:33 INFO - Return code: 0 05:31:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:31: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')]} 05:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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', '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 0x7f9cfc61ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2867f60>, '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 0x2868d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28691d0>, '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': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', '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 05:31: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:31: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 psutil>=3.1.1 05:31:33 INFO - Using env: {'DISPLAY': ':0', 05:31:33 INFO - 'HOME': '/home/cltbld', 05:31:33 INFO - 'LANG': 'en_US.UTF-8', 05:31:33 INFO - 'LANGUAGE': 'en_US:en', 05:31:33 INFO - 'LOGNAME': 'cltbld', 05:31:33 INFO - 'MAIL': '/var/mail/cltbld', 05:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:33 INFO - 'MOZ_NO_REMOTE': '1', 05:31:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:33 INFO - 'NO_EM_RESTART': '1', 05:31:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:33 INFO - 'PWD': '/builds/slave/test', 05:31:33 INFO - 'SHELL': '/bin/bash', 05:31:33 INFO - 'SHLVL': '1', 05:31:33 INFO - 'TERM': 'linux', 05:31:33 INFO - 'TMOUT': '86400', 05:31:33 INFO - 'USER': 'cltbld', 05:31:33 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', 05:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:33 INFO - '_': '/tools/buildbot/bin/python'} 05:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:33 INFO - Downloading/unpacking psutil>=3.1.1 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:31:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:35 INFO - Installing collected packages: psutil 05:31:35 INFO - Running setup.py install for psutil 05:31:35 INFO - building 'psutil._psutil_linux' extension 05:31:35 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 05:31:36 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 05:31:36 INFO - building 'psutil._psutil_posix' extension 05:31:36 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 05:31:36 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 05:31:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:36 INFO - Successfully installed psutil 05:31:36 INFO - Cleaning up... 05:31:36 INFO - Return code: 0 05:31:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:31:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9cfc61ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2867f60>, '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 0x2868d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28691d0>, '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': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', '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 05:31:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:31:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:31:36 INFO - Using env: {'DISPLAY': ':0', 05:31:36 INFO - 'HOME': '/home/cltbld', 05:31:36 INFO - 'LANG': 'en_US.UTF-8', 05:31:36 INFO - 'LANGUAGE': 'en_US:en', 05:31:36 INFO - 'LOGNAME': 'cltbld', 05:31:36 INFO - 'MAIL': '/var/mail/cltbld', 05:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:36 INFO - 'MOZ_NO_REMOTE': '1', 05:31:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:36 INFO - 'NO_EM_RESTART': '1', 05:31:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:36 INFO - 'PWD': '/builds/slave/test', 05:31:36 INFO - 'SHELL': '/bin/bash', 05:31:36 INFO - 'SHLVL': '1', 05:31:36 INFO - 'TERM': 'linux', 05:31:36 INFO - 'TMOUT': '86400', 05:31:36 INFO - 'USER': 'cltbld', 05:31:36 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', 05:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:36 INFO - '_': '/tools/buildbot/bin/python'} 05:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:31:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:31:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:31:38 INFO - Installing collected packages: mozsystemmonitor 05:31:38 INFO - Running setup.py install for mozsystemmonitor 05:31:38 INFO - Successfully installed mozsystemmonitor 05:31:38 INFO - Cleaning up... 05:31:38 INFO - Return code: 0 05:31:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:31: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')]} 05:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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', '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 0x7f9cfc61ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2867f60>, '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 0x2868d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28691d0>, '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': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', '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 05:31: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:31: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 jsonschema==2.5.1 05:31:38 INFO - Using env: {'DISPLAY': ':0', 05:31:38 INFO - 'HOME': '/home/cltbld', 05:31:38 INFO - 'LANG': 'en_US.UTF-8', 05:31:38 INFO - 'LANGUAGE': 'en_US:en', 05:31:38 INFO - 'LOGNAME': 'cltbld', 05:31:38 INFO - 'MAIL': '/var/mail/cltbld', 05:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:38 INFO - 'MOZ_NO_REMOTE': '1', 05:31:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:38 INFO - 'NO_EM_RESTART': '1', 05:31:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:38 INFO - 'PWD': '/builds/slave/test', 05:31:38 INFO - 'SHELL': '/bin/bash', 05:31:38 INFO - 'SHLVL': '1', 05:31:38 INFO - 'TERM': 'linux', 05:31:38 INFO - 'TMOUT': '86400', 05:31:38 INFO - 'USER': 'cltbld', 05:31:38 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', 05:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:38 INFO - '_': '/tools/buildbot/bin/python'} 05:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:38 INFO - Downloading/unpacking jsonschema==2.5.1 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:31:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:40 INFO - Downloading functools32-3.2.3-2.tar.gz 05:31:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:31:41 INFO - warning: no files found matching '*.txt' 05:31:41 INFO - no previously-included directories found matching 'build' 05:31:41 INFO - no previously-included directories found matching 'dist' 05:31:41 INFO - no previously-included directories found matching '.git*' 05:31:41 INFO - Installing collected packages: jsonschema, functools32 05:31:41 INFO - Running setup.py install for functools32 05:31:41 INFO - warning: no files found matching '*.txt' 05:31:41 INFO - no previously-included directories found matching 'build' 05:31:41 INFO - no previously-included directories found matching 'dist' 05:31:41 INFO - no previously-included directories found matching '.git*' 05:31:41 INFO - Successfully installed jsonschema functools32 05:31:41 INFO - Cleaning up... 05:31:41 INFO - Return code: 0 05:31:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:31: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')]} 05:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9cfc61ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2867f60>, '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 0x2868d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28691d0>, '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': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', '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 05:31:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:31:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:31:41 INFO - Using env: {'DISPLAY': ':0', 05:31:41 INFO - 'HOME': '/home/cltbld', 05:31:41 INFO - 'LANG': 'en_US.UTF-8', 05:31:41 INFO - 'LANGUAGE': 'en_US:en', 05:31:41 INFO - 'LOGNAME': 'cltbld', 05:31:41 INFO - 'MAIL': '/var/mail/cltbld', 05:31:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:41 INFO - 'MOZ_NO_REMOTE': '1', 05:31:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:41 INFO - 'NO_EM_RESTART': '1', 05:31:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:41 INFO - 'PWD': '/builds/slave/test', 05:31:41 INFO - 'SHELL': '/bin/bash', 05:31:41 INFO - 'SHLVL': '1', 05:31:41 INFO - 'TERM': 'linux', 05:31:41 INFO - 'TMOUT': '86400', 05:31:41 INFO - 'USER': 'cltbld', 05:31:41 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', 05:31:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:41 INFO - '_': '/tools/buildbot/bin/python'} 05:31:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:31:41 INFO - Cleaning up... 05:31:41 INFO - Return code: 0 05:31:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:31: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')]} 05:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9cfc61ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2867f60>, '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 0x2868d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28691d0>, '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': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', '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 05:31:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:31:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:31:41 INFO - Using env: {'DISPLAY': ':0', 05:31:41 INFO - 'HOME': '/home/cltbld', 05:31:41 INFO - 'LANG': 'en_US.UTF-8', 05:31:41 INFO - 'LANGUAGE': 'en_US:en', 05:31:41 INFO - 'LOGNAME': 'cltbld', 05:31:41 INFO - 'MAIL': '/var/mail/cltbld', 05:31:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:41 INFO - 'MOZ_NO_REMOTE': '1', 05:31:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:41 INFO - 'NO_EM_RESTART': '1', 05:31:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:41 INFO - 'PWD': '/builds/slave/test', 05:31:41 INFO - 'SHELL': '/bin/bash', 05:31:41 INFO - 'SHLVL': '1', 05:31:41 INFO - 'TERM': 'linux', 05:31:41 INFO - 'TMOUT': '86400', 05:31:41 INFO - 'USER': 'cltbld', 05:31:41 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', 05:31:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:41 INFO - '_': '/tools/buildbot/bin/python'} 05:31:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:41 INFO - Downloading/unpacking blobuploader==1.2.4 05:31:41 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 05:31:41 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 05:31:41 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 05:31:41 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 05:31:41 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 05:31:41 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 05:31:43 INFO - Downloading blobuploader-1.2.4.tar.gz 05:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:31:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:31:43 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 05:31:43 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 05:31:43 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 05:31:43 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 05:31:43 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 05:31:43 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 05:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:31:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:31:43 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 05:31:43 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 05:31:43 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 05:31:43 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 05:31:43 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 05:31:43 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 05:31:44 INFO - Downloading docopt-0.6.1.tar.gz 05:31:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:31:44 INFO - Installing collected packages: blobuploader, requests, docopt 05:31:44 INFO - Running setup.py install for blobuploader 05:31:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:31:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:31:44 INFO - Running setup.py install for requests 05:31:44 INFO - Running setup.py install for docopt 05:31:44 INFO - Successfully installed blobuploader requests docopt 05:31:44 INFO - Cleaning up... 05:31:44 INFO - Return code: 0 05:31:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:44 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 0x7f9cfc61ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2867f60>, '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 0x2868d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28691d0>, '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': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', '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 05:31:44 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 05:31:44 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 05:31:44 INFO - Using env: {'DISPLAY': ':0', 05:31:44 INFO - 'HOME': '/home/cltbld', 05:31:44 INFO - 'LANG': 'en_US.UTF-8', 05:31:44 INFO - 'LANGUAGE': 'en_US:en', 05:31:44 INFO - 'LOGNAME': 'cltbld', 05:31:44 INFO - 'MAIL': '/var/mail/cltbld', 05:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:44 INFO - 'MOZ_NO_REMOTE': '1', 05:31:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:44 INFO - 'NO_EM_RESTART': '1', 05:31:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:44 INFO - 'PWD': '/builds/slave/test', 05:31:44 INFO - 'SHELL': '/bin/bash', 05:31:44 INFO - 'SHLVL': '1', 05:31:44 INFO - 'TERM': 'linux', 05:31:44 INFO - 'TMOUT': '86400', 05:31:44 INFO - 'USER': 'cltbld', 05:31:44 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', 05:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:44 INFO - '_': '/tools/buildbot/bin/python'} 05:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:31:45 INFO - Running setup.py (path:/tmp/pip-3VHzvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:31:45 INFO - Running setup.py (path:/tmp/pip-K8Flre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:31:45 INFO - Running setup.py (path:/tmp/pip-j12fsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:31:45 INFO - Running setup.py (path:/tmp/pip-TDGfh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:31:45 INFO - Running setup.py (path:/tmp/pip-UoBt5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:31:45 INFO - Running setup.py (path:/tmp/pip-uDbDV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:31:45 INFO - Running setup.py (path:/tmp/pip-ObRDUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:31:45 INFO - Running setup.py (path:/tmp/pip-2KbnI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:31:45 INFO - Running setup.py (path:/tmp/pip-ACpqwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:31:45 INFO - Running setup.py (path:/tmp/pip-5ilHiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:31:45 INFO - Running setup.py (path:/tmp/pip-mbtXCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:31:45 INFO - Running setup.py (path:/tmp/pip-Iga0bC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:31:45 INFO - Running setup.py (path:/tmp/pip-d2VMjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:31:46 INFO - Running setup.py (path:/tmp/pip-Rdt1aZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:46 INFO - Running setup.py (path:/tmp/pip-bolEax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:31:46 INFO - Running setup.py (path:/tmp/pip-jjGmOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:31:46 INFO - Running setup.py (path:/tmp/pip-GK1DeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:31:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:31:46 INFO - Running setup.py install for manifestparser 05:31:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:31:46 INFO - Running setup.py install for mozcrash 05:31:46 INFO - Running setup.py install for mozdebug 05:31:46 INFO - Running setup.py install for mozdevice 05:31:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:31:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:31:46 INFO - Running setup.py install for mozfile 05:31:46 INFO - Running setup.py install for mozhttpd 05:31:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:31:46 INFO - Running setup.py install for mozinfo 05:31:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Running setup.py install for mozInstall 05:31:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Running setup.py install for mozleak 05:31:47 INFO - Running setup.py install for mozlog 05:31:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Running setup.py install for moznetwork 05:31:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Running setup.py install for mozprocess 05:31:47 INFO - Running setup.py install for mozprofile 05:31:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Running setup.py install for mozrunner 05:31:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:31:47 INFO - Running setup.py install for mozscreenshot 05:31:47 INFO - Running setup.py install for moztest 05:31:48 INFO - Running setup.py install for mozversion 05:31:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:31:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:31:48 INFO - Cleaning up... 05:31:48 INFO - Return code: 0 05:31:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31: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')]} 05:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:48 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 0x7f9cfc61ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2867f60>, '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 0x2868d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28691d0>, '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': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', '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 05:31:48 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 05:31:48 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 05:31:48 INFO - Using env: {'DISPLAY': ':0', 05:31:48 INFO - 'HOME': '/home/cltbld', 05:31:48 INFO - 'LANG': 'en_US.UTF-8', 05:31:48 INFO - 'LANGUAGE': 'en_US:en', 05:31:48 INFO - 'LOGNAME': 'cltbld', 05:31:48 INFO - 'MAIL': '/var/mail/cltbld', 05:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:48 INFO - 'MOZ_NO_REMOTE': '1', 05:31:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:48 INFO - 'NO_EM_RESTART': '1', 05:31:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:48 INFO - 'PWD': '/builds/slave/test', 05:31:48 INFO - 'SHELL': '/bin/bash', 05:31:48 INFO - 'SHLVL': '1', 05:31:48 INFO - 'TERM': 'linux', 05:31:48 INFO - 'TMOUT': '86400', 05:31:48 INFO - 'USER': 'cltbld', 05:31:48 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', 05:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:48 INFO - '_': '/tools/buildbot/bin/python'} 05:31:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:31:48 INFO - Running setup.py (path:/tmp/pip-NOK7r2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:31:48 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)) 05:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:31:48 INFO - Running setup.py (path:/tmp/pip-QtNov7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:31:48 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)) 05:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:31:48 INFO - Running setup.py (path:/tmp/pip-OquET0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:31:48 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)) 05:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:31:48 INFO - Running setup.py (path:/tmp/pip-Ky0Erj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:31:48 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)) 05:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:31:48 INFO - Running setup.py (path:/tmp/pip-A4IqEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:31:48 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)) 05:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:31:48 INFO - Running setup.py (path:/tmp/pip-Cu6lzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:31:48 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)) 05:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:31:48 INFO - Running setup.py (path:/tmp/pip-fsIGx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:31:48 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)) 05:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:31:48 INFO - Running setup.py (path:/tmp/pip-oBLVHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:31:48 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)) 05:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:31:48 INFO - Running setup.py (path:/tmp/pip-VAwyz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:31:49 INFO - Running setup.py (path:/tmp/pip-HB9CSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:31:49 INFO - Running setup.py (path:/tmp/pip-Y3oPtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:31:49 INFO - Running setup.py (path:/tmp/pip-B9c4Y6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:31:49 INFO - Running setup.py (path:/tmp/pip-tna_X1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:31:49 INFO - Running setup.py (path:/tmp/pip-oR0f4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:49 INFO - Running setup.py (path:/tmp/pip-eO3Enk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:31:49 INFO - Running setup.py (path:/tmp/pip-q81Wto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:31:49 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)) 05:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:31:49 INFO - Running setup.py (path:/tmp/pip-EDn_mM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:31:49 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)) 05:31:49 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)) 05:31:49 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)) 05:31:49 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)) 05:31:49 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)) 05:31:49 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)) 05:31:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:51 INFO - Downloading blessings-1.6.tar.gz 05:31:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:31:51 INFO - Installing collected packages: blessings 05:31:51 INFO - Running setup.py install for blessings 05:31:51 INFO - Successfully installed blessings 05:31:51 INFO - Cleaning up... 05:31:51 INFO - Return code: 0 05:31:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:31:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:51 INFO - Reading from file tmpfile_stdout 05:31:51 INFO - Current package versions: 05:31:51 INFO - argparse == 1.2.1 05:31:51 INFO - blessings == 1.6 05:31:51 INFO - blobuploader == 1.2.4 05:31:51 INFO - docopt == 0.6.1 05:31:51 INFO - functools32 == 3.2.3-2 05:31:51 INFO - jsonschema == 2.5.1 05:31:51 INFO - manifestparser == 1.1 05:31:51 INFO - mozInstall == 1.12 05:31:51 INFO - mozcrash == 0.17 05:31:51 INFO - mozdebug == 0.1 05:31:51 INFO - mozdevice == 0.48 05:31:51 INFO - mozfile == 1.2 05:31:51 INFO - mozhttpd == 0.7 05:31:51 INFO - mozinfo == 0.9 05:31:51 INFO - mozleak == 0.1 05:31:51 INFO - mozlog == 3.3 05:31:51 INFO - moznetwork == 0.27 05:31:51 INFO - mozprocess == 0.23 05:31:51 INFO - mozprofile == 0.28 05:31:51 INFO - mozrunner == 6.12 05:31:51 INFO - mozscreenshot == 0.1 05:31:51 INFO - mozsystemmonitor == 0.3 05:31:51 INFO - moztest == 0.7 05:31:51 INFO - mozversion == 1.4 05:31:51 INFO - psutil == 3.1.1 05:31:51 INFO - requests == 1.2.3 05:31:51 INFO - wsgiref == 0.1.2 05:31:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:51 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')]} 05:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:51 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')]} 05:31:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:51 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 0x7f9cfc61ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2867f60>, '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 0x2868d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28691d0>, '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': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', '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 05:31:51 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 05:31:51 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 05:31:51 INFO - Using env: {'DISPLAY': ':0', 05:31:51 INFO - 'HOME': '/home/cltbld', 05:31:51 INFO - 'LANG': 'en_US.UTF-8', 05:31:51 INFO - 'LANGUAGE': 'en_US:en', 05:31:51 INFO - 'LOGNAME': 'cltbld', 05:31:51 INFO - 'MAIL': '/var/mail/cltbld', 05:31:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:51 INFO - 'MOZ_NO_REMOTE': '1', 05:31:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:51 INFO - 'NO_EM_RESTART': '1', 05:31:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:51 INFO - 'PWD': '/builds/slave/test', 05:31:51 INFO - 'SHELL': '/bin/bash', 05:31:51 INFO - 'SHLVL': '1', 05:31:51 INFO - 'TERM': 'linux', 05:31:51 INFO - 'TMOUT': '86400', 05:31:51 INFO - 'USER': 'cltbld', 05:31:51 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', 05:31:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:51 INFO - '_': '/tools/buildbot/bin/python'} 05:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:52 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)) 05:31:52 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)) 05:31:52 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)) 05:31:52 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)) 05:31:52 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)) 05:31:52 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)) 05:31:52 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)) 05:31:52 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)) 05:31:52 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)) 05:31:52 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)) 05:31:52 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)) 05:31:52 INFO - Cleaning up... 05:31:52 INFO - Return code: 0 05:31:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:31:52 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')]} 05:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:52 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')]} 05:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:52 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 0x7f9cfc61ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x286aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2867f60>, '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 0x2868d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28691d0>, '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': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', '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 05:31:52 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 05:31:52 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 05:31:52 INFO - Using env: {'DISPLAY': ':0', 05:31:52 INFO - 'HOME': '/home/cltbld', 05:31:52 INFO - 'LANG': 'en_US.UTF-8', 05:31:52 INFO - 'LANGUAGE': 'en_US:en', 05:31:52 INFO - 'LOGNAME': 'cltbld', 05:31:52 INFO - 'MAIL': '/var/mail/cltbld', 05:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:52 INFO - 'MOZ_NO_REMOTE': '1', 05:31:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:52 INFO - 'NO_EM_RESTART': '1', 05:31:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:52 INFO - 'PWD': '/builds/slave/test', 05:31:52 INFO - 'SHELL': '/bin/bash', 05:31:52 INFO - 'SHLVL': '1', 05:31:52 INFO - 'TERM': 'linux', 05:31:52 INFO - 'TMOUT': '86400', 05:31:52 INFO - 'USER': 'cltbld', 05:31:52 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', 05:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:52 INFO - '_': '/tools/buildbot/bin/python'} 05:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:31:52 INFO - Cleaning up... 05:31:52 INFO - Return code: 0 05:31:52 INFO - Running post-action listener: _resource_record_post_action 05:31:52 INFO - Running post-action listener: _start_resource_monitoring 05:31:52 INFO - Starting resource monitoring. 05:31:52 INFO - [mozharness: 2016-09-16 12:31:52.445572Z] Finished create-virtualenv step (success) 05:31:52 INFO - [mozharness: 2016-09-16 12:31:52.446032Z] Running install step. 05:31:52 INFO - Running pre-action listener: _resource_record_pre_action 05:31:52 INFO - Running main action method: install 05:31:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:52 INFO - Reading from file tmpfile_stdout 05:31:52 INFO - Detecting whether we're running mozinstall >=1.0... 05:31:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:31:52 INFO - Reading from file tmpfile_stdout 05:31:52 INFO - Output received: 05:31:52 INFO - Usage: mozinstall [options] installer 05:31:52 INFO - Options: 05:31:52 INFO - -h, --help show this help message and exit 05:31:52 INFO - -d DEST, --destination=DEST 05:31:52 INFO - Directory to install application into. [default: 05:31:52 INFO - "/builds/slave/test"] 05:31:52 INFO - --app=APP Application being installed. [default: firefox] 05:31:52 INFO - mkdir: /builds/slave/test/build/application 05:31:52 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'] 05:31:52 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 05:32:09 INFO - Reading from file tmpfile_stdout 05:32:09 INFO - Output received: 05:32:09 INFO - /builds/slave/test/build/application/firefox/firefox 05:32:09 INFO - Running post-action listener: _resource_record_post_action 05:32:09 INFO - [mozharness: 2016-09-16 12:32:09.328037Z] Finished install step (success) 05:32:09 INFO - [mozharness: 2016-09-16 12:32:09.328304Z] Running run-tests step. 05:32:09 INFO - Running pre-action listener: _resource_record_pre_action 05:32:09 INFO - Running main action method: run_tests 05:32:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:32:09 INFO - Python 2.7.3 05:32:09 INFO - Return code: 0 05:32:09 INFO - grabbing minidump binary from tooltool 05:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:09 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 0x2867f60>, '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 0x2868d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28691d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:32:09 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 05:32:09 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 05:32:09 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 05:32:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:32:09 INFO - Return code: 0 05:32:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:32:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:32:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:32:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:32:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:32:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 05:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 05:32:09 INFO - Using env: {'DISPLAY': ':0', 05:32:09 INFO - 'HOME': '/home/cltbld', 05:32:09 INFO - 'LANG': 'en_US.UTF-8', 05:32:09 INFO - 'LANGUAGE': 'en_US:en', 05:32:09 INFO - 'LOGNAME': 'cltbld', 05:32:09 INFO - 'MAIL': '/var/mail/cltbld', 05:32:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:32:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:09 INFO - 'MOZ_NO_REMOTE': '1', 05:32:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:32:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:09 INFO - 'NO_EM_RESTART': '1', 05:32:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:09 INFO - 'PWD': '/builds/slave/test', 05:32:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:32:09 INFO - 'SHELL': '/bin/bash', 05:32:09 INFO - 'SHLVL': '1', 05:32:09 INFO - 'TERM': 'linux', 05:32:09 INFO - 'TMOUT': '86400', 05:32:09 INFO - 'USER': 'cltbld', 05:32:09 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1474028783.291941-547746672', 05:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:09 INFO - '_': '/tools/buildbot/bin/python'} 05:32:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 05:32:09 INFO - mozversion application_buildid: 20160916035715 05:32:09 INFO - mozversion application_changeset: f1dbeb5dee22cabc56b2cf25d2eab1435abbf131 05:32:09 INFO - mozversion application_display_name: Nightly 05:32:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:32:09 INFO - mozversion application_name: Firefox 05:32:09 INFO - mozversion application_remotingname: firefox 05:32:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:32:09 INFO - mozversion application_vendor: Mozilla 05:32:09 INFO - mozversion application_version: 51.0a1 05:32:09 INFO - mozversion platform_buildid: 20160916035715 05:32:09 INFO - mozversion platform_changeset: f1dbeb5dee22cabc56b2cf25d2eab1435abbf131 05:32:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:32:09 INFO - mozversion platform_version: 51.0a1 05:32:09 INFO - using testdate: 1474029129 05:32:09 INFO - actual date: 1474029129 05:32:09 INFO - starting webserver on 'localhost:60903' 05:32:09 INFO - SUITE-START | Running 2 tests 05:32:09 INFO - TEST-START | dromaeo_css 05:32:09 INFO - operating with platform_type : linux_ 05:32:09 INFO - Initialising browser for dromaeo_css test... 05:32:09 INFO - TEST-INFO | started process 22627 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpESIUDO/profile http://localhost:60903/getInfo.html) 05:32:14 INFO - PROCESS | 22627 | __metrics Screen width/height:1600/1200 05:32:14 INFO - PROCESS | 22627 | colorDepth:24 05:32:14 INFO - PROCESS | 22627 | Browser inner width/height: 1024/697 05:32:14 INFO - PROCESS | 22627 | __metrics 05:32:15 INFO - PROCESS | 22627 | 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" 05:32:15 INFO - PROCESS | 22627 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:32:15 INFO - PROCESS | 22627 | console.error: PushService: 05:32:15 INFO - PROCESS | 22627 | stateChangeProcessEnqueue: Error transitioning state 05:32:15 INFO - PROCESS | 22627 | UnknownError 05:32:16 INFO - TEST-INFO | 22627: exit 0 05:32:16 INFO - Browser initialized. 05:32:16 INFO - Running cycle 1/1 for dromaeo_css test... 05:32:16 INFO - TEST-INFO | started process 22706 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpESIUDO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:28 INFO - PROCESS | 22706 | RSS: Main: 174735360 05:32:28 INFO - PROCESS | 22706 | 05:32:33 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:32:40 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:32:54 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:32:58 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 05:33:06 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 6.63385 seconds. 05:33:07 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:33:13 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:33:20 INFO - PROCESS | 22706 | 1474029200254 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] 05:33:29 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 05:33:34 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:33:41 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 05:33:50 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.03344 seconds. 05:33:53 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:33:57 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 05:34:04 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.06677 seconds. 05:34:06 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:34:08 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:34:16 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.08341 seconds. 05:34:27 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:34:51 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.39999 seconds. 05:35:11 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 05:35:19 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.76679 seconds. 05:35:21 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:35:27 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:35:32 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 05:35:32 INFO - PROCESS | 22706 | Cycle 1(1): loaded http://localhost:60903/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60903/tests/dromaeo/cssquery-ext.html) 05:35:33 INFO - PROCESS | 22706 | RSS: Main: 300994560 05:35:33 INFO - PROCESS | 22706 | 05:35:44 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 05:35:50 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:35:54 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:36:14 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:36:24 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:36:39 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:36:41 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:36:43 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 05:36:45 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:36:53 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:36:55 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:36:57 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:37:04 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:37:33 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 05:37:37 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:37:42 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:37:52 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:37:54 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:37:57 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:37:58 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:38:01 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 05:38:18 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:38:20 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:38:22 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:38:24 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:38:26 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:38:28 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:38:36 INFO - PROCESS | 22706 | Cycle 1(1): loaded http://localhost:60903/tests/dromaeo/cssquery-ext.html (next: http://localhost:60903/tests/dromaeo/cssquery-jquery.html) 05:38:37 INFO - PROCESS | 22706 | RSS: Main: 272142336 05:38:37 INFO - PROCESS | 22706 | 05:38:43 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 05:38:51 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 05:38:53 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:38:55 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:38:57 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:39:02 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:39:12 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:39:14 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:39:30 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:39:46 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:39:54 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:39:56 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:40:04 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:40:08 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:40:16 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:40:21 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:40:22 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:40:24 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:40:26 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:40:34 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:40:36 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:40:44 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:40:51 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:40:53 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 05:40:55 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:41:09 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:41:13 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:41:15 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:41:17 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 05:41:23 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 05:41:26 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:41:29 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:41:31 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:41:33 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:41:35 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:41:37 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:41:39 INFO - PROCESS | 22706 | Cycle 1(1): loaded http://localhost:60903/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60903/tests/dromaeo/cssquery-mootools.html) 05:41:40 INFO - PROCESS | 22706 | RSS: Main: 220143616 05:41:40 INFO - PROCESS | 22706 | 05:41:45 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 05:41:48 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:41:54 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:41:58 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:42:00 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:42:06 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:42:08 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:42:10 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 05:42:14 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:42:19 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:42:23 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:42:25 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:42:29 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 05:42:31 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:42:47 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:42:49 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 05:42:51 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:43:07 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:43:15 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 05:43:27 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:43:33 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:43:39 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:43:47 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:44:01 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:44:10 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:44:20 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:44:22 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:44:30 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:44:42 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:44:42 INFO - PROCESS | 22706 | Cycle 1(1): loaded http://localhost:60903/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60903/tests/dromaeo/cssquery-prototype.html) 05:44:43 INFO - PROCESS | 22706 | RSS: Main: 219422720 05:44:43 INFO - PROCESS | 22706 | 05:44:57 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 05:44:59 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:45:08 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:45:19 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:45:20 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:45:22 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:45:34 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:45:37 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:45:46 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:45:48 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 05:45:50 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:46:08 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:46:21 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 05:46:37 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:46:41 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:46:45 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 05:46:47 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:46:51 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:46:59 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:47:11 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:47:27 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:47:46 INFO - PROCESS | 22706 | Cycle 1(1): loaded http://localhost:60903/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60903/tests/dromaeo/cssquery-yui.html) 05:47:46 INFO - PROCESS | 22706 | RSS: Main: 219676672 05:47:46 INFO - PROCESS | 22706 | 05:47:50 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 05:47:57 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:47:59 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:48:05 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:48:09 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00214 seconds. 05:48:45 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 05:48:50 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:48:52 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:48:54 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:48:56 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:48:58 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:49:04 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:49:11 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:49:16 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:49:19 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:49:20 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:49:32 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:49:38 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:49:41 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:49:44 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:49:54 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:49:56 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:50:01 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:50:03 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:50:13 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:50:19 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:50:21 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:50:42 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 05:50:43 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 05:50:45 INFO - PROCESS | 22706 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 05:50:49 INFO - PROCESS | 22706 | Cycle 1(1): loaded http://localhost:60903/tests/dromaeo/cssquery-yui.html (next: http://localhost:60903/tests/dromaeo/cssquery-dojo.html) 05:50:50 INFO - PROCESS | 22706 | RSS: Main: 304902144 05:50:50 INFO - PROCESS | 22706 | 05:50:50 INFO - PROCESS | 22706 | __start_tp_report 05:50:50 INFO - PROCESS | 22706 | _x_x_mozilla_page_load 05:50:50 INFO - PROCESS | 22706 | _x_x_mozilla_page_load_details 05:50:50 INFO - PROCESS | 22706 | |i|pagename|runs| 05:50:50 INFO - PROCESS | 22706 | |0;dojo.html;2618.3454136465884;2642.907498237562;2646.192911162097;2665.7067722550514;2678.772304354131;4660.137874493221;4713.222318317482;4761.500042495536;4843.418789745227;4893.33939918111;4735.692180008297;4751.738654374008;4751.786169622366;4752.358431611731;4777.092352453032;4342.869713908588;4366.1049484855685;4436.933445998313;4474.373587697725;4505.3917721107555;4347.586979236968;4391.780410979454;4425.8450954216605;4445.933311000337;4478.97760511197;259.96323507107275;4446.355278484618;4447.733136011833;4486.932696009562;4497.550244975492;4370.147821174872;4442.777861106932;4450.354698568722;4460.8661740147845;4465.419495465569;4361.563843615645;4368.039031413084;4369.191699535596;4372.650187984954;4418.64650827933;4745.786439610226;4777.378940737682;4779.402574678165;4800.4719480857075;4837.806487740521;4860.878478038077;4875.9756201219125;4887.828925987609;5105.566026887719;5144.845654630367;5203.7918483261;5209.661372010845;5210.166373380241;5250.7637156328055;5273.604479664003;4842.515748425165;4842.733649649233;4843.2250839865455;4852.126617208936;4904.288878112654;5135.666181698178;5163.974180129076;5190.844274671766;5319.414864364917;5321.813736519203;4984.576311013604;5005.649604527648;5006.274090256892;5074.188129899198;5075.56857667095;5085.949140508622;5087.389513258358;5088.974555127201;5095.108356037678;5110.233464980209;4981.12830254704;5031.77357018935;5031.7987280509105;5041.168207245837;5052.797888084509;4976.576990955737;5039.244113383022;5062.7215503146945;5078.746062696924;5139.254808052811;4345.695801293942;4361.171377438277;4362.258416069276;4366.890827729332;4391.494978077498;3772.7170462215445;3816.6946644268946;3825.158465137665;3857.903552411156;3888.9611103889165;4398.142362239332;4406.339049142654;4414.823407063745;4438.134563760036;4449.354843547668;5141.948580514222;5144.819931302386;5146.871328216825;5147.459516750762;5193.662411943212;4438.28987362027;4441.444819397575;4442.911141777146;4491.214037543482;4497.707649002804;4521.977390113094;4526.547345265513;4565.086982603492;4567.109413664303;4604.769761511911;4768.403949506312;4783.689060211075;4793.592544633745;4847.127517046965;4847.5394837490385;5056.696598204119;5066.113430149783;5071.323265728948;5075.746212689424;5135.38375394955;4619.676622636383;4706.670533062653;4767.976160119178;4773.9045219096115;4823.493533179106;4967.7019378837385;5053.519915608012;5065.873353166053;5088.2876397305145;5136.8202112925865;5110.61670374716;5136.512031357015;5144.717040562805;5152.871178220575;5214.765335559985;4833.879153021203;5005.374328208974;5013.273075403973;5013.323201367914;5015.3730783652045;4826.58973987215;4851.272309153655;4866.05112003156;4882.267659851051;4927.802887884588;4780.0917825614415;4781.617470602413;4787.257975013878;4790.281457781221;4842.104210721028;4879.34128892595;4879.438864530624;4889.413270407432;4900.656954013328;4951.851444456672;4731.313959475788;4778.354922085613;4863.513648635108;4932.358793356841;4940.876478087933 05:50:50 INFO - PROCESS | 22706 | |1;ext.html;550.6530885542109;564.1227890630068;565.5758181363977;566.575068698476;573.7819628541154;28057.158285251426;38292;39491;39573.20853582927;42961;40684.79657601715;41120.79439602805;41203.381949270784;41217.38173927394;41218;7146.928530714705;7634.312911837934;7742.303192712683;7752.573608451533;7793.610319484032;7121.501494895333;7330.376917961953;7335.119785625732;7342.77971660851;9847.901520984806;9742.561584728704;10072.093511583957;10105.494725263745;10111.393316401003;10113.089821916026;10088.092071713545;10152.79694406115;10158.39049657023;10173.440460774655;10173.694789156338;9991.150752186073;10068.798624027479;10075.949620251851;10076.69769906904;10077.54651040705;37433.62566374329;37831.62168378309;37852;37867.62132378696;37912.43131353032;2760.489309477733;2786.5680819473014;2816.985915070432;3065.6781037990913;3141.27750617357;28457.430851382855;28799.71200287991;28893.27766805847;29322.266943326373;29932.401351973058;3366.7138293245102;3498.2828520153307;3528.649725959143;3534.29314137171;3558.839852206657;248215;248507;248904;249076;249327;30422.391552168832;50854.74572627113;51048.48951510512;51163.488365115874;51196;31442.842785785924;33036;33226.335473290885;33446.8327658365;33537.83231083829;11499.252548584314;11693.941530292293;11696.88303116958;11700.2979821211;11704.122190835722;9149.862752058725;9233.769155771159;9249.722508324761;9276.443413395084;9297.442153470678;7121.857562848714;7365;7470.70117195317;7807.68769249235;7828.530288182614;3050.80169788963;3069.4321550513228;3087.92280192997;3090.2119959410206;3098.752099832008;836.9372297077743;853.5049671190818;893.3746377535751;903.4940433357341;931.1526510875207;25752.356191094997;25821.3544661385;26195;26420.471590568082;27946.161615151577;893.8346405914926;928.5310917986509;944.8818897637796;954.5752140297501;996.342414006752;13763;14724.263786810616;14985.32566034531;15205.61985950338;15214.771778423332;383379;386221;387199.28007199615;387433;387785;41957.370639440436;43089.13821723548;47318.29022564664;48071.278930815366;48235;1114.5653195253858;1144.66804626659;1146.2091157101572;1146.380954284677;1147.1912301827192;26908.596371054842;28014.859925700242;28575.14274571766;28802.711972880843;29310.853445732635;41018.58981410148;41486;41498;41519.58480415278;41533.1693366125;316752;371491;383202;383447;604190;36680.69873711379;37142.07144821401;37162.442563362114;37478;37946.810265948494;40771;40999.38500922429;41187;41282.587174127875;41889.58110418857;35782.105447364025;35806.462903057;35912.10219744423;36113.638863612076;36214.09464763296;35579.46630800592;35787.28425431529;35915.28169436545;36383.45424818577;36558;35251.64748352586;35415.82292088523;35637;35691;36031.81984090168;31234.531482028244;34714.82642586872;35550;35623.28753424865;35911.82044089763;24702.505949881262;24855.87572062128;25125.979843224995;25186.24441266765;25509.489810204064 05:50:50 INFO - PROCESS | 22706 | |2;jquery.html;331.6848993456217;347.30192313449965;350.68788777987595;351.62903137190256;375.44059351566176;10765.515551800168;14562.854371456282;15044.548663540085;16412.09733464659;16621.91689041556;16495.917520412382;16860.156992150372;17143.657126857455;17186.312547498084;17227.655446891054;4764.094821983829;4766.356541866847;4873.22028475444;4923.384576927884;5015.147424937751;3596.5504311961004;4852;4930.334404855353;4934.062528119646;4935.210366341386;3767.8304476298495;5508.091164957777;5820.796272130477;5849.619774714652;5872.647641141545;5700.059490184115;5764.33710123337;5839.649621022731;5839.9124013139835;5852.502537284336;5205.765740541685;5205.817796377147;5209.218617207449;5214;5216;20854.47863803402;21187.258445954318;22028.449288767748;22069;22073;1947.4449781812257;2086.2802333195177;2255.4586899144088;2379.0840526397287;2455.4475243070165;13666.11170273929;14248.92875535626;14293;14297.356618952172;15431;1636.9754453683204;1644.6710657868355;1646.9208444320714;1652.9834701652949;1659.4772646616398;76896.61551692261;84218.57890710507;84771.67511573722;85456.57271713665;86113.56943215306;14713.926430367994;15980.600484987968;16435.26041328143;16629.6674066518;16999.66000680004;29040.41919161605;29117.85441072781;29145.417091658048;29229.269268268465;29365.265868353035;8096.595170241582;8238.09380968093;8278.213569710868;8607.397482176313;8647.524386158695;6599.373059559334;6705.865882682319;6845.349691779273;6905.1713794345;6905.482088843357;8085.63614637331;8114.229148230908;8428.704995325133;8476.830463390697;8639.222469977607;3109.5335699645234;3180.507021411685;3327.4842399428126;3328.301056778104;3344.772294735592;755.882838160075;796.1759578835877;808.0303635637259;826.9007719073629;886.2024178239636;15953.361865525481;16197.919010405109;16742.330306787593;16745.748813767805;17379.65240695179;830.7424698343533;831.2767891933935;854.6453221912836;857.5240741388465;858.3776761847736;8329.95835020821;9241.168294853496;9615.278854085971;9632.32573719847;9705.708828735149;393541;398696;406087;406931;407778;79735.60132199418;80074;80609.59695201604;81233;84154.57922710347;856.7472595584193;866.3415803989013;873.6854732296368;887.6360692116201;890.0609856601347;14356.425742970165;14953.626159346104;15219.543413697607;15261.923690381476;15676.451324203597;37072.44391334078;37283.81358093192;38194.618053819104;38562.80718596389;39036.219275614894;49622.75188624034;50687;51463.22805158001;52365.7381713089;52792;7440.590767507839;7475.36459400957;7567.091948966159;7670.616469176407;7708.922910771045;7682.654280557276;7920.881186782318;7962.323202527618;8647.783805404915;8913.688020919237;6888.862222755617;7054.224035356209;7215.314545118205;7312.634368281459;7856.528608283521;7470.925290747023;7513.812154696177;7693.192214817587;7883;7979.241972012649;7345.853082938418;7664.233576642291;7727.884081738666;7779.688812447439;7836;10933.890661093605;11106.389148596905;11213.60752373663;11237.775244495228;11498.94250528742;10320.43237621938;10435.37387756737;10490.632827850988;10860.402677852793;11507.769844602893 05:50:50 INFO - PROCESS | 22706 | |3;mootools.html;1206.017096066706;1308.3654427602612;1358.9728205435893;1379.4206433298014;1662.8170901200865;6568.310327415618;7402.851942961144;7731.729389471369;7737.148913619498;7756.379489640823;7600.429967752413;7667.616619169047;7678.884816727754;7725;7736.922630773691;2114.5030917734357;2137.957240855182;2144.9034793434334;2149.9462513437134;2151.709519214902;2050.589882023594;2086.2385229391316;2113.17586141405;2113.9788602114013;2114.503091773432;2063.319104695447;2097.3603051069417;2113.165299706615;2116.798904104108;2141.892905354734;2030.2589554812535;2032.1464984706458;2034.0236686390467;2034.8982550872545;2038.398672391648;2002.7096071069614;2015.8992050397421;2081.2819577245828;2083.1354987680247;2086.7495900491886;7240.637968101574;7487;7691.769246922601;7721.22787721229;7742.806429839299;4116.835326586933;4237.258479766028;4237.851675191354;4240.936385954213;4243.321068629036;2610.85640289784;2716.103685783706;2764.3918337965615;2768.307923019245;2769.8892044318404;949.4493193947494;955.507913424587;969.9806003879883;977.4233202410541;978.838491648877;10375.221858360577;10493.370397776082;10533.525991330409;10578;10582.259241853073;3738.2149748553106;3889.572147063821;3914.1388894443176;3954.8813535593977;4140.772257525805;8275.213854683796;8323.960629645775;8371.58142092893;8374.078851326349;8377.036640786266;4212.115455754326;4225.281702110648;4236.9364459533135;4237.385579091015;4240.872773816791;3336.666333366644;3441.913952151216;3768.660820526166;3949.19041596472;3960.1881614269005;3236.6277878044325;3294.40700673881;3368.1242876852193;3369.1745522347182;3408.7784294020807;1008.9798204035878;1010.7978404319166;1019.0828254570946;1020.2705065877933;1037.984430233547;654.306435178712;681.4003676764415;740.2930201657405;756.092688773463;785.0932173339802;4901.803927842846;4941.703497790144;5171.870703232449;5175.611829112831;5178.741062946838;375.1446701520538;385.2353079137909;387.37439035956726;422.0946070678378;424.4863714904938;3745.5318085239346;3831.6934645228316;3902.726809123391;3909.58949310324;3910.2013073986286;8705.390622656354;9115.179633832855;9136.406133601427;9174.495402752913;9190.678326258683;9070.818583628423;9074.319426043074;9106.726798196065;9109.863352049724;9139.634414623473;6894;7178.533395329391;7225;7236.204017558064;7237.7466788661095;5349.304590403222;5392.514673679388;5392.568594512508;5574.749136288796;5638.126090455986;8231.34149268045;8421.95789021051;8523.105073967392;8642.141939483041;8703.738887833375;8585.270252028648;8910.59902304385;8936.955315223642;8974.326925480484;9049.411788233743;5815.563832712479;6031.457168854824;6349.206349206349;6591.967040164768;6652.933470665232;7073.681684324115;7317.743878964211;7321.816954576178;7327.487075904635;7539.246075392417;6378.808635740749;6389.7763578274535;6472.15861937945;6584.473242140713;6625.602463852184;6388.9041664376;6439.098526206241;6460.160179176677;6489.772857949949;6573.868522629617;5988.700564971647;6096.177016102769;6105.45050945417;6111.96944015277;6112.72492737819;4469.620082292911;4469.932951005672;4473.418455600881;4553.2942393929;4565.954340456686;4450.933236001398;4455.933161002523;4478.843240486567;4546.636269098531;4601.309803529497 05:50:50 INFO - PROCESS | 22706 | |4;prototype.html;288.67235687494696;291.5481004443113;296.77000324748315;297.8361900954475;300.07576663875267;4877.7804998775055;4927.975360123194;4928.753562321887;4958.785097651072;4964.32981547491;4824.975875120619;4913.80344786209;4933.1367010773065;4935.6545041847085;4940.135476291653;3620.981895090521;3752.7748335099845;3753.2118255166447;3818;3824.445455408964;3820.9044773880737;3823.1589050408866;3831.9233615327676;3857.3828187490008;3869.438931354952;3803.790791506466;3824.2160357126863;3831.865884694036;3835.539735231776;3851.326017946862;3624.401973674354;3739.6821270192067;3759.1917737686254;3810.8475660973527;3821;3381.4927760835826;3389.711874490671;3390.55922730046;3390.9660903390895;3394.913627639132;4714.5521175488275;4782;4789.425268967711;4795.544423279783;4800.687955282895;4038.414429907647;4306.181825453185;4390.034192477649;4408.1624491346865;4408.911821763579;5047.570956468705;5055.241713742931;5059.974700126476;5073.847784566469;5125.282460455539;3577.69063616154;3584.390653588896;3599.2621512590117;3617.222297206087;3630.9636903631153;5046.520580544842;5352.2506849041165;5362.383325917531;5380.730963451851;5399.6490228135435;3613.584437789635;3660.5424321959754;3665.2486240321205;3668.908277293089;3675.320065787784;3714.0900479382185;3724.162063535737;3733.178700685845;3755.286495565834;3755.6619904208205;4288.035192081757;4299.914001719948;4319.157764235943;4322.891927701769;4334.956650433455;4853.95146048535;5076.873078173076;5124.9743751281;5154.226865970135;5190.325257716473;1286.8841804237663;1300.2003767682806;1300.8243887075314;1301.4859130643342;1304.3804193008245;790.4308897593723;825.7068740597122;828.7099515169642;844.8225872566829;845.045099038083;4375.78121093944;4388.670849686287;4492.775361231991;4540.409746732969;4551.544845515488;5217.556507696812;5220.843374698765;5246.950609878039;5253;5254.185601231815;4495.213337665896;4509.323601459807;4513.7066090704;4546.227141385973;4554.294084416921;2623.8556879371818;2762.5441802102428;2772.986135069312;2778.6804517480346;2780.7497325240824;4917.729524876095;4918.3360246367;4939.5554400104165;4941.827036053793;4958.975205123951;5121.820736274212;5267.183586544092;5366.785328586813;5368;5378.973105134528;4879.463259041502;4904.896398310351;4906.386701662293;4930.506949305112;4953.950460495421;5162.716050617252;5174.27560141588;5174.586033117417;5203.635745497779;5231.346081739783;4996.527433276995;5021.44764075944;5125.154349532435;5153.896922061613;5155;4922.803087876445;4948.05986862495;4967.850964471072;4985.70085794839;4995.5004499551205;3643.908902277464;3656.542932133483;3661.132822260613;3664;3672.485851980671;5023.146065169003;5251.186066159751;5295.046891559557;5300.655457395257;5301.231321458444;5029.899402012013;5032.27032080339;5043.899122017612;5073.568746656428;5094.541491265803;5046.142155833444;5070;5072.746362681925;5083.72039537829;5113.207452844815;4756.643251756063;5013.573846222966;5018.297438358706;5021.397432308147;5035.622328325317;4899.240617704261;5034;5134.178531434953;5161.122609156378;5174.586033117417;4900.5099490052135;5040.798368065236;5046.545810877039;5051.898962020666;5056.292119103402 05:50:50 INFO - PROCESS | 22706 | |5;yui.html;591.3938213331336;656.5371413153728;660.102260925142;672.2806596941275;683.8871586188279;12992.350382480874;13502.729945401099;13816.792748108774;14010;14020.22888741119;14778.852211477883;15013.099214047164;15051.774223386657;15066.773998390005;15068.849311506881;745.7613563659631;766.7124828189429;769.8229407236339;770.0374531835206;770.1951460723529;598.377687205306;614.8708771158063;618.1068356225256;620.0296535921291;744.9553026818381;769.4114002787871;798.1579433697447;799.0571126071234;803.9879401808978;804.4248362420868;794.8847417124484;795.1650766694976;796.024869534821;796.0924546017487;796.2634563029233;460.88477880529865;583.0292562882795;583.0525396231125;583.151514546334;583.1777194156219;10409.219308551888;13013.674658133528;13433.19400835953;13473.797893031613;13985.650358741012;12503.437345319482;13181.86818131816;13241.271730054843;13362.33188340579;13693.863061369359;10506.474676266233;10636.946815265874;10728.195385346131;10791.730206744816;10860.511276992554;3173.873045078194;3184.5700830388064;3184.8885289014775;3254.3653987472453;3355.2282974915906;65551.67224163849;66025.00962485519;66734.3326566733;68059;68529.65735171342;10052.698419047294;10401.167906567456;10509.264351495322;10564.154867610572;10703.250772445854;18885.62228755417;18928.905355473136;19035.238590456505;19071;19077.236910523425;6590.077389165521;6688.598684078874;6762.898556521656;6915.271182204448;6993.4055605274125;3519.2081781598936;3568.2685049564775;3574.5531808523933;3655.5065066216252;3785.1861849702445;2662.853543055112;2757.5725762506845;2770.473610014091;2784.0673374419393;2785.2062162284055;472.8203282752532;477.5487164629461;479.8080767692881;484.8375794109013;491.83769356112396;537.4974398937006;538.3190264315643;545.4164044472376;548.3721139295563;558.1906235957878;8010.959945200237;8197.139300373494;8201;8262.42163048581;8429.578521073921;562.3055526424862;571.4085921071671;571.4828080587075;576.109910188751;592.6589969620018;3798.9362978366103;3848.9037774055873;3971.980140099281;3972.3445631471063;4015.2371049500503;57482;58337;59854.40145598575;106229.46885265679;109227.45386273018;89230;89884.5505772467;91114;91799;96012.03987960031;81357.18642813496;81828.59085704653;82004;82570;82621.17378826135;7693.346065584488;7812.453128280966;7942.682292708227;8075.434719569808;8094.716684915999;46028.539714602426;46199.76900115612;46286;46825.76587117043;47044;51540;52450;55247;60330.396696032476;62481.375186247555;11179.496922638664;11469.483873225558;11561.595344162914;11778.941105294418;11910;11896.76206475883;11926.165168438127;12040.638780836589;12065.698357541132;12097.33464659417;10972.890271097187;11394.145439092266;12077.39613019363;12400.441980110736;13142.80285795733;10711.94644026775;10776.622818201326;10865.511052002521;11447.656570302905;11535.76928461443;10965.56137754481;11248.212625116163;11263.943680281545;12790.808137878126;12944.15862968904;9306.860397094184;9321.30090243221;10276.537555810157;10406.791864162826;10667.195596658008;9215.354925155174;10306.896931030593;10400.739981500523;10426.843597346198;10511.94744026275 05:50:50 INFO - PROCESS | 22706 | __end_tp_report 05:50:50 INFO - PROCESS | 22706 | __start_cc_report 05:50:50 INFO - PROCESS | 22706 | _x_x_mozilla_cycle_collect,1081 05:50:50 INFO - PROCESS | 22706 | __end_cc_report 05:50:50 INFO - PROCESS | 22706 | __startTimestamp1474030250110__endTimestamp 05:50:50 INFO - PROCESS | 22706 | 05:50:50 INFO - PROCESS | 22706 | ------- Summary: start ------- 05:50:50 INFO - PROCESS | 22706 | Number of tests: 6 05:50:50 INFO - PROCESS | 22706 | 05:50:50 INFO - PROCESS | 22706 | [#0] dojo.html Cycles:165 Average:4690.28 Median:4833.88 stddev:596.11 (12.3%) stddev-sans-first:575.36 05:50:50 INFO - PROCESS | 22706 | Values: 2618.3 2642.9 2646.2 2665.7 2678.8 4660.1 4713.2 4761.5 4843.4 4893.3 4735.7 4751.7 4751.8 4752.4 4777.1 4342.9 4366.1 4436.9 4474.4 4505.4 4347.6 4391.8 4425.8 4445.9 4479.0 260.0 4446.4 4447.7 4486.9 4497.6 4370.1 4442.8 4450.4 4460.9 4465.4 4361.6 4368.0 4369.2 4372.7 4418.6 4745.8 4777.4 4779.4 4800.5 4837.8 4860.9 4876.0 4887.8 5105.6 5144.8 5203.8 5209.7 5210.2 5250.8 5273.6 4842.5 4842.7 4843.2 4852.1 4904.3 5135.7 5164.0 5190.8 5319.4 5321.8 4984.6 5005.6 5006.3 5074.2 5075.6 5085.9 5087.4 5089.0 5095.1 5110.2 4981.1 5031.8 5031.8 5041.2 5052.8 4976.6 5039.2 5062.7 5078.7 5139.3 4345.7 4361.2 4362.3 4366.9 4391.5 3772.7 3816.7 3825.2 3857.9 3889.0 4398.1 4406.3 4414.8 4438.1 4449.4 5141.9 5144.8 5146.9 5147.5 5193.7 4438.3 4441.4 4442.9 4491.2 4497.7 4522.0 4526.5 4565.1 4567.1 4604.8 4768.4 4783.7 4793.6 4847.1 4847.5 5056.7 5066.1 5071.3 5075.7 5135.4 4619.7 4706.7 4768.0 4773.9 4823.5 4967.7 5053.5 5065.9 5088.3 5136.8 5110.6 5136.5 5144.7 5152.9 5214.8 4833.9 5005.4 5013.3 5013.3 5015.4 4826.6 4851.3 4866.1 4882.3 4927.8 4780.1 4781.6 4787.3 4790.3 4842.1 4879.3 4879.4 4889.4 4900.7 4951.9 4731.3 4778.4 4863.5 4932.4 4940.9 05:50:50 INFO - PROCESS | 22706 | 05:50:50 INFO - PROCESS | 22706 | [#1] ext.html Cycles:180 Average:48947.00 Median:35973.55 stddev:95752.09 (266.2%) stddev-sans-first:95951.75 05:50:50 INFO - PROCESS | 22706 | Values: 550.7 564.1 565.6 566.6 573.8 28057.2 38292.0 39491.0 39573.2 42961.0 40684.8 41120.8 41203.4 41217.4 41218.0 7146.9 7634.3 7742.3 7752.6 7793.6 7121.5 7330.4 7335.1 7342.8 9847.9 9742.6 10072.1 10105.5 10111.4 10113.1 10088.1 10152.8 10158.4 10173.4 10173.7 9991.2 10068.8 10075.9 10076.7 10077.5 37433.6 37831.6 37852.0 37867.6 37912.4 2760.5 2786.6 2817.0 3065.7 3141.3 28457.4 28799.7 28893.3 29322.3 29932.4 3366.7 3498.3 3528.6 3534.3 3558.8 248215.0 248507.0 248904.0 249076.0 249327.0 30422.4 50854.7 51048.5 51163.5 51196.0 31442.8 33036.0 33226.3 33446.8 33537.8 11499.3 11693.9 11696.9 11700.3 11704.1 9149.9 9233.8 9249.7 9276.4 9297.4 7121.9 7365.0 7470.7 7807.7 7828.5 3050.8 3069.4 3087.9 3090.2 3098.8 836.9 853.5 893.4 903.5 931.2 25752.4 25821.4 26195.0 26420.5 27946.2 893.8 928.5 944.9 954.6 996.3 13763.0 14724.3 14985.3 15205.6 15214.8 383379.0 386221.0 387199.3 387433.0 387785.0 41957.4 43089.1 47318.3 48071.3 48235.0 1114.6 1144.7 1146.2 1146.4 1147.2 26908.6 28014.9 28575.1 28802.7 29310.9 41018.6 41486.0 41498.0 41519.6 41533.2 316752.0 371491.0 383202.0 383447.0 604190.0 36680.7 37142.1 37162.4 37478.0 37946.8 40771.0 40999.4 41187.0 41282.6 41889.6 35782.1 35806.5 35912.1 36113.6 36214.1 35579.5 35787.3 35915.3 36383.5 36558.0 35251.6 35415.8 35637.0 35691.0 36031.8 31234.5 34714.8 35550.0 35623.3 35911.8 24702.5 24855.9 25126.0 25186.2 25509.5 05:50:50 INFO - PROCESS | 22706 | 05:50:50 INFO - PROCESS | 22706 | [#2] jquery.html Cycles:180 Average:25846.97 Median:27318.95 stddev:66748.28 (244.3%) stddev-sans-first:66908.03 05:50:50 INFO - PROCESS | 22706 | Values: 331.7 347.3 350.7 351.6 375.4 10765.5 14562.9 15044.5 16412.1 16621.9 16495.9 16860.2 17143.7 17186.3 17227.7 4764.1 4766.4 4873.2 4923.4 5015.1 3596.6 4852.0 4930.3 4934.1 4935.2 3767.8 5508.1 5820.8 5849.6 5872.6 5700.1 5764.3 5839.6 5839.9 5852.5 5205.8 5205.8 5209.2 5214.0 5216.0 20854.5 21187.3 22028.4 22069.0 22073.0 1947.4 2086.3 2255.5 2379.1 2455.4 13666.1 14248.9 14293.0 14297.4 15431.0 1637.0 1644.7 1646.9 1653.0 1659.5 76896.6 84218.6 84771.7 85456.6 86113.6 14713.9 15980.6 16435.3 16629.7 16999.7 29040.4 29117.9 29145.4 29229.3 29365.3 8096.6 8238.1 8278.2 8607.4 8647.5 6599.4 6705.9 6845.3 6905.2 6905.5 8085.6 8114.2 8428.7 8476.8 8639.2 3109.5 3180.5 3327.5 3328.3 3344.8 755.9 796.2 808.0 826.9 886.2 15953.4 16197.9 16742.3 16745.7 17379.7 830.7 831.3 854.6 857.5 858.4 8330.0 9241.2 9615.3 9632.3 9705.7 393541.0 398696.0 406087.0 406931.0 407778.0 79735.6 80074.0 80609.6 81233.0 84154.6 856.7 866.3 873.7 887.6 890.1 14356.4 14953.6 15219.5 15261.9 15676.5 37072.4 37283.8 38194.6 38562.8 39036.2 49622.8 50687.0 51463.2 52365.7 52792.0 7440.6 7475.4 7567.1 7670.6 7708.9 7682.7 7920.9 7962.3 8647.8 8913.7 6888.9 7054.2 7215.3 7312.6 7856.5 7470.9 7513.8 7693.2 7883.0 7979.2 7345.9 7664.2 7727.9 7779.7 7836.0 10933.9 11106.4 11213.6 11237.8 11498.9 10320.4 10435.4 10490.6 10860.4 11507.8 05:50:50 INFO - PROCESS | 22706 | 05:50:50 INFO - PROCESS | 22706 | [#3] mootools.html Cycles:180 Average:4927.24 Median:4921.75 stddev:2801.36 (56.9%) stddev-sans-first:2795.26 05:50:50 INFO - PROCESS | 22706 | Values: 1206.0 1308.4 1359.0 1379.4 1662.8 6568.3 7402.9 7731.7 7737.1 7756.4 7600.4 7667.6 7678.9 7725.0 7736.9 2114.5 2138.0 2144.9 2149.9 2151.7 2050.6 2086.2 2113.2 2114.0 2114.5 2063.3 2097.4 2113.2 2116.8 2141.9 2030.3 2032.1 2034.0 2034.9 2038.4 2002.7 2015.9 2081.3 2083.1 2086.7 7240.6 7487.0 7691.8 7721.2 7742.8 4116.8 4237.3 4237.9 4240.9 4243.3 2610.9 2716.1 2764.4 2768.3 2769.9 949.4 955.5 970.0 977.4 978.8 10375.2 10493.4 10533.5 10578.0 10582.3 3738.2 3889.6 3914.1 3954.9 4140.8 8275.2 8324.0 8371.6 8374.1 8377.0 4212.1 4225.3 4236.9 4237.4 4240.9 3336.7 3441.9 3768.7 3949.2 3960.2 3236.6 3294.4 3368.1 3369.2 3408.8 1009.0 1010.8 1019.1 1020.3 1038.0 654.3 681.4 740.3 756.1 785.1 4901.8 4941.7 5171.9 5175.6 5178.7 375.1 385.2 387.4 422.1 424.5 3745.5 3831.7 3902.7 3909.6 3910.2 8705.4 9115.2 9136.4 9174.5 9190.7 9070.8 9074.3 9106.7 9109.9 9139.6 6894.0 7178.5 7225.0 7236.2 7237.7 5349.3 5392.5 5392.6 5574.7 5638.1 8231.3 8422.0 8523.1 8642.1 8703.7 8585.3 8910.6 8937.0 8974.3 9049.4 5815.6 6031.5 6349.2 6592.0 6652.9 7073.7 7317.7 7321.8 7327.5 7539.2 6378.8 6389.8 6472.2 6584.5 6625.6 6388.9 6439.1 6460.2 6489.8 6573.9 5988.7 6096.2 6105.5 6112.0 6112.7 4469.6 4469.9 4473.4 4553.3 4566.0 4450.9 4455.9 4478.8 4546.6 4601.3 05:50:50 INFO - PROCESS | 22706 | 05:50:50 INFO - PROCESS | 22706 | [#4] prototype.html Cycles:180 Average:4226.30 Median:4899.88 stddev:1232.69 (25.2%) stddev-sans-first:1200.20 05:50:50 INFO - PROCESS | 22706 | Values: 288.7 291.5 296.8 297.8 300.1 4877.8 4928.0 4928.8 4958.8 4964.3 4825.0 4913.8 4933.1 4935.7 4940.1 3621.0 3752.8 3753.2 3818.0 3824.4 3820.9 3823.2 3831.9 3857.4 3869.4 3803.8 3824.2 3831.9 3835.5 3851.3 3624.4 3739.7 3759.2 3810.8 3821.0 3381.5 3389.7 3390.6 3391.0 3394.9 4714.6 4782.0 4789.4 4795.5 4800.7 4038.4 4306.2 4390.0 4408.2 4408.9 5047.6 5055.2 5060.0 5073.8 5125.3 3577.7 3584.4 3599.3 3617.2 3631.0 5046.5 5352.3 5362.4 5380.7 5399.6 3613.6 3660.5 3665.2 3668.9 3675.3 3714.1 3724.2 3733.2 3755.3 3755.7 4288.0 4299.9 4319.2 4322.9 4335.0 4854.0 5076.9 5125.0 5154.2 5190.3 1286.9 1300.2 1300.8 1301.5 1304.4 790.4 825.7 828.7 844.8 845.0 4375.8 4388.7 4492.8 4540.4 4551.5 5217.6 5220.8 5247.0 5253.0 5254.2 4495.2 4509.3 4513.7 4546.2 4554.3 2623.9 2762.5 2773.0 2778.7 2780.7 4917.7 4918.3 4939.6 4941.8 4959.0 5121.8 5267.2 5366.8 5368.0 5379.0 4879.5 4904.9 4906.4 4930.5 4954.0 5162.7 5174.3 5174.6 5203.6 5231.3 4996.5 5021.4 5125.2 5153.9 5155.0 4922.8 4948.1 4967.9 4985.7 4995.5 3643.9 3656.5 3661.1 3664.0 3672.5 5023.1 5251.2 5295.0 5300.7 5301.2 5029.9 5032.3 5043.9 5073.6 5094.5 5046.1 5070.0 5072.7 5083.7 5113.2 4756.6 5013.6 5018.3 5021.4 5035.6 4899.2 5034.0 5134.2 5161.1 5174.6 4900.5 5040.8 5046.5 5051.9 5056.3 05:50:50 INFO - PROCESS | 22706 | 05:50:50 INFO - PROCESS | 22706 | [#5] yui.html Cycles:180 Average:17710.51 Median:46114.15 stddev:25262.52 (54.8%) stddev-sans-first:25300.69 05:50:50 INFO - PROCESS | 22706 | Values: 591.4 656.5 660.1 672.3 683.9 12992.4 13502.7 13816.8 14010.0 14020.2 14778.9 15013.1 15051.8 15066.8 15068.8 745.8 766.7 769.8 770.0 770.2 598.4 614.9 618.1 620.0 745.0 769.4 798.2 799.1 804.0 804.4 794.9 795.2 796.0 796.1 796.3 460.9 583.0 583.1 583.2 583.2 10409.2 13013.7 13433.2 13473.8 13985.7 12503.4 13181.9 13241.3 13362.3 13693.9 10506.5 10636.9 10728.2 10791.7 10860.5 3173.9 3184.6 3184.9 3254.4 3355.2 65551.7 66025.0 66734.3 68059.0 68529.7 10052.7 10401.2 10509.3 10564.2 10703.3 18885.6 18928.9 19035.2 19071.0 19077.2 6590.1 6688.6 6762.9 6915.3 6993.4 3519.2 3568.3 3574.6 3655.5 3785.2 2662.9 2757.6 2770.5 2784.1 2785.2 472.8 477.5 479.8 484.8 491.8 537.5 538.3 545.4 548.4 558.2 8011.0 8197.1 8201.0 8262.4 8429.6 562.3 571.4 571.5 576.1 592.7 3798.9 3848.9 3972.0 3972.3 4015.2 57482.0 58337.0 59854.4 106229.5 109227.5 89230.0 89884.6 91114.0 91799.0 96012.0 81357.2 81828.6 82004.0 82570.0 82621.2 7693.3 7812.5 7942.7 8075.4 8094.7 46028.5 46199.8 46286.0 46825.8 47044.0 51540.0 52450.0 55247.0 60330.4 62481.4 11179.5 11469.5 11561.6 11778.9 11910.0 11896.8 11926.2 12040.6 12065.7 12097.3 10972.9 11394.1 12077.4 12400.4 13142.8 10711.9 10776.6 10865.5 11447.7 11535.8 10965.6 11248.2 11263.9 12790.8 12944.2 9306.9 9321.3 10276.5 10406.8 10667.2 9215.4 10306.9 10400.7 10426.8 10511.9 05:50:50 INFO - PROCESS | 22706 | -------- Summary: end -------- 05:50:50 INFO - PROCESS | 22706 | 05:50:51 INFO - TEST-INFO | 22706: exit 0 05:50:51 INFO - TEST-OK | dromaeo_css | took 1121520ms 05:50:51 INFO - TEST-START | kraken 05:50:51 INFO - operating with platform_type : linux_ 05:50:51 INFO - Initialising browser for kraken test... 05:50:51 INFO - TEST-INFO | started process 23016 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpujH9s3/profile http://localhost:60903/getInfo.html) 05:50:55 INFO - PROCESS | 23016 | __metrics Screen width/height:1600/1200 05:50:55 INFO - PROCESS | 23016 | colorDepth:24 05:50:55 INFO - PROCESS | 23016 | Browser inner width/height: 1024/697 05:50:55 INFO - PROCESS | 23016 | __metrics 05:50:56 INFO - PROCESS | 23016 | 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" 05:50:56 INFO - PROCESS | 23016 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:50:56 INFO - PROCESS | 23016 | console.error: PushService: 05:50:56 INFO - PROCESS | 23016 | stateChangeProcessEnqueue: Error transitioning state 05:50:56 INFO - PROCESS | 23016 | UnknownError 05:50:57 INFO - TEST-INFO | 23016: exit 0 05:50:57 INFO - Browser initialized. 05:50:57 INFO - Running cycle 1/1 for kraken test... 05:50:57 INFO - TEST-INFO | started process 23094 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpujH9s3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:51:09 INFO - PROCESS | 23094 | RSS: Main: 175337472 05:51:09 INFO - PROCESS | 23094 | 05:51:12 INFO - PROCESS | 23094 | Cycle 1(1): loaded http://localhost:60903/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60903/tests/kraken/driver.html?testName=audio-beat-detection) 05:51:13 INFO - PROCESS | 23094 | RSS: Main: 234090496 05:51:13 INFO - PROCESS | 23094 | 05:51:16 INFO - PROCESS | 23094 | Cycle 1(1): loaded http://localhost:60903/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60903/tests/kraken/driver.html?testName=audio-dft) 05:51:16 INFO - PROCESS | 23094 | RSS: Main: 218275840 05:51:16 INFO - PROCESS | 23094 | 05:51:20 INFO - PROCESS | 23094 | Cycle 1(1): loaded http://localhost:60903/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60903/tests/kraken/driver.html?testName=audio-fft) 05:51:20 INFO - PROCESS | 23094 | RSS: Main: 300711936 05:51:20 INFO - PROCESS | 23094 | 05:51:22 INFO - PROCESS | 23094 | Cycle 1(1): loaded http://localhost:60903/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60903/tests/kraken/driver.html?testName=audio-oscillator) 05:51:23 INFO - PROCESS | 23094 | RSS: Main: 210022400 05:51:23 INFO - PROCESS | 23094 | 05:51:25 INFO - PROCESS | 23094 | Cycle 1(1): loaded http://localhost:60903/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60903/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:51:26 INFO - PROCESS | 23094 | RSS: Main: 213446656 05:51:26 INFO - PROCESS | 23094 | 05:51:29 INFO - PROCESS | 23094 | Cycle 1(1): loaded http://localhost:60903/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60903/tests/kraken/driver.html?testName=imaging-darkroom) 05:51:30 INFO - PROCESS | 23094 | RSS: Main: 250089472 05:51:30 INFO - PROCESS | 23094 | 05:51:33 INFO - PROCESS | 23094 | Cycle 1(1): loaded http://localhost:60903/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60903/tests/kraken/driver.html?testName=imaging-desaturate) 05:51:34 INFO - PROCESS | 23094 | RSS: Main: 244506624 05:51:34 INFO - PROCESS | 23094 | 05:51:37 INFO - PROCESS | 23094 | Cycle 1(1): loaded http://localhost:60903/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60903/tests/kraken/driver.html?testName=json-parse-financial) 05:51:37 INFO - PROCESS | 23094 | RSS: Main: 246910976 05:51:37 INFO - PROCESS | 23094 | 05:51:39 INFO - PROCESS | 23094 | Cycle 1(1): loaded http://localhost:60903/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60903/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:51:40 INFO - PROCESS | 23094 | RSS: Main: 210464768 05:51:40 INFO - PROCESS | 23094 | 05:51:42 INFO - PROCESS | 23094 | Cycle 1(1): loaded http://localhost:60903/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60903/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:51:42 INFO - PROCESS | 23094 | RSS: Main: 218206208 05:51:42 INFO - PROCESS | 23094 | 05:51:44 INFO - PROCESS | 23094 | Cycle 1(1): loaded http://localhost:60903/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60903/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:51:45 INFO - PROCESS | 23094 | RSS: Main: 222208000 05:51:45 INFO - PROCESS | 23094 | 05:51:48 INFO - PROCESS | 23094 | Cycle 1(1): loaded http://localhost:60903/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60903/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:51:49 INFO - PROCESS | 23094 | RSS: Main: 222883840 05:51:49 INFO - PROCESS | 23094 | 05:51:52 INFO - PROCESS | 23094 | Cycle 1(1): loaded http://localhost:60903/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60903/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:51:52 INFO - PROCESS | 23094 | RSS: Main: 221245440 05:51:52 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | Cycle 1(1): loaded http://localhost:60903/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60903/tests/kraken/driver.html?testName=ai-astar) 05:51:54 INFO - PROCESS | 23094 | RSS: Main: 272236544 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | __start_tp_report 05:51:54 INFO - PROCESS | 23094 | _x_x_mozilla_page_load 05:51:54 INFO - PROCESS | 23094 | _x_x_mozilla_page_load_details 05:51:54 INFO - PROCESS | 23094 | |i|pagename|runs| 05:51:54 INFO - PROCESS | 23094 | |0;ai-astar;113;115;116;116;116;115;114;116;114;116 05:51:54 INFO - PROCESS | 23094 | |1;audio-beat-detection;159;124;128;155;130;127;124;127;170;130 05:51:54 INFO - PROCESS | 23094 | |2;audio-dft;162;162;162;163;162;161;163;160;161;162 05:51:54 INFO - PROCESS | 23094 | |3;audio-fft;77;78;78;112;76;78;88;76;100;75 05:51:54 INFO - PROCESS | 23094 | |4;audio-oscillator;84;83;139;84;85;84;117;83;82;124 05:51:54 INFO - PROCESS | 23094 | |5;imaging-gaussian-blur;118;117;117;117;117;117;116;117;116;118 05:51:54 INFO - PROCESS | 23094 | |6;imaging-darkroom;147;145;145;147;147;147;146;146;146;146 05:51:54 INFO - PROCESS | 23094 | |7;imaging-desaturate;95;94;95;95;95;95;96;95;94;95 05:51:54 INFO - PROCESS | 23094 | |8;json-parse-financial;63;63;63;62;65;65;65;65;65;72 05:51:54 INFO - PROCESS | 23094 | |9;json-stringify-tinderbox;66;67;66;67;67;66;65;66;67;66 05:51:54 INFO - PROCESS | 23094 | |10;stanford-crypto-aes;85;89;82;83;86;85;88;86;82;82 05:51:54 INFO - PROCESS | 23094 | |11;stanford-crypto-ccm;142;142;131;132;145;142;134;132;180;140 05:51:54 INFO - PROCESS | 23094 | |12;stanford-crypto-pbkdf2;178;175;171;172;177;175;171;172;169;170 05:51:54 INFO - PROCESS | 23094 | |13;stanford-crypto-sha256-iterative;70;73;69;69;70;69;72;73;69;68 05:51:54 INFO - PROCESS | 23094 | __end_tp_report 05:51:54 INFO - PROCESS | 23094 | __start_cc_report 05:51:54 INFO - PROCESS | 23094 | _x_x_mozilla_cycle_collect,2014 05:51:54 INFO - PROCESS | 23094 | __end_cc_report 05:51:54 INFO - PROCESS | 23094 | __startTimestamp1474030314744__endTimestamp 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | ------- Summary: start ------- 05:51:54 INFO - PROCESS | 23094 | Number of tests: 14 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | [#0] ai-astar Cycles:10 Average:115.10 Median:116.00 stddev:1.10 (0.9%) stddev-sans-first:0.87 05:51:54 INFO - PROCESS | 23094 | Values: 113.0 115.0 116.0 116.0 116.0 115.0 114.0 116.0 114.0 116.0 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:130.00 stddev:17.04 (13.1%) stddev-sans-first:16.18 05:51:54 INFO - PROCESS | 23094 | Values: 159.0 124.0 128.0 155.0 130.0 127.0 124.0 127.0 170.0 130.0 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 05:51:54 INFO - PROCESS | 23094 | Values: 162.0 162.0 162.0 163.0 162.0 161.0 163.0 160.0 161.0 162.0 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | [#3] audio-fft Cycles:10 Average:83.80 Median:77.50 stddev:12.57 (16.2%) stddev-sans-first:13.09 05:51:54 INFO - PROCESS | 23094 | Values: 77.0 78.0 78.0 112.0 76.0 78.0 88.0 76.0 100.0 75.0 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:83.50 stddev:21.50 (25.7%) stddev-sans-first:22.32 05:51:54 INFO - PROCESS | 23094 | Values: 84.0 83.0 139.0 84.0 85.0 84.0 117.0 83.0 82.0 124.0 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 05:51:54 INFO - PROCESS | 23094 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 118.0 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.50 stddev:0.79 (0.5%) stddev-sans-first:0.78 05:51:54 INFO - PROCESS | 23094 | Values: 147.0 145.0 145.0 147.0 147.0 147.0 146.0 146.0 146.0 146.0 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 05:51:54 INFO - PROCESS | 23094 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 96.0 95.0 94.0 95.0 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | [#8] json-parse-financial Cycles:10 Average:64.80 Median:65.00 stddev:2.78 (4.3%) stddev-sans-first:2.87 05:51:54 INFO - PROCESS | 23094 | Values: 63.0 63.0 63.0 62.0 65.0 65.0 65.0 65.0 65.0 72.0 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.50 stddev:0.67 (1.0%) stddev-sans-first:0.71 05:51:54 INFO - PROCESS | 23094 | Values: 66.0 67.0 66.0 67.0 67.0 66.0 65.0 66.0 67.0 66.0 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:85.50 stddev:2.53 (3.0%) stddev-sans-first:2.68 05:51:54 INFO - PROCESS | 23094 | Values: 85.0 89.0 82.0 83.0 86.0 85.0 88.0 86.0 82.0 82.0 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | [#11] stanford-crypto-ccm Cycles:10 Average:142.00 Median:142.00 stddev:14.31 (10.1%) stddev-sans-first:15.17 05:51:54 INFO - PROCESS | 23094 | Values: 142.0 142.0 131.0 132.0 145.0 142.0 134.0 132.0 180.0 140.0 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:173.50 stddev:3.06 (1.8%) stddev-sans-first:2.65 05:51:54 INFO - PROCESS | 23094 | Values: 178.0 175.0 171.0 172.0 177.0 175.0 171.0 172.0 169.0 170.0 05:51:54 INFO - PROCESS | 23094 | 05:51:54 INFO - PROCESS | 23094 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:70.00 stddev:1.81 (2.6%) stddev-sans-first:1.92 05:51:54 INFO - PROCESS | 23094 | Values: 70.0 73.0 69.0 69.0 70.0 69.0 72.0 73.0 69.0 68.0 05:51:54 INFO - PROCESS | 23094 | -------- Summary: end -------- 05:51:54 INFO - PROCESS | 23094 | 05:51:56 INFO - TEST-INFO | 23094: exit 0 05:51:56 INFO - TEST-OK | kraken | took 64929ms 05:51:56 INFO - SUITE-END | took 1186s 05:51:56 INFO - Completed test suite (00:19:46) 05:51:56 INFO - javascript benchmark 05:51:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2618.3454136465884, 2642.907498237562, 2646.192911162097, 2665.7067722550514, 2678.772304354131, 4660.137874493221, 4713.222318317482, 4761.500042495536, 4843.418789745227, 4893.33939918111, 4735.692180008297, 4751.738654374008, 4751.786169622366, 4752.358431611731, 4777.092352453032, 4342.869713908588, 4366.1049484855685, 4436.933445998313, 4474.373587697725, 4505.3917721107555, 4347.586979236968, 4391.780410979454, 4425.8450954216605, 4445.933311000337, 4478.97760511197, 259.96323507107275, 4446.355278484618, 4447.733136011833, 4486.932696009562, 4497.550244975492, 4370.147821174872, 4442.777861106932, 4450.354698568722, 4460.8661740147845, 4465.419495465569, 4361.563843615645, 4368.039031413084, 4369.191699535596, 4372.650187984954, 4418.64650827933, 4745.786439610226, 4777.378940737682, 4779.402574678165, 4800.4719480857075, 4837.806487740521, 4860.878478038077, 4875.9756201219125, 4887.828925987609, 5105.566026887719, 5144.845654630367, 5203.7918483261, 5209.661372010845, 5210.166373380241, 5250.7637156328055, 5273.604479664003, 4842.515748425165, 4842.733649649233, 4843.2250839865455, 4852.126617208936, 4904.288878112654, 5135.666181698178, 5163.974180129076, 5190.844274671766, 5319.414864364917, 5321.813736519203, 4984.576311013604, 5005.649604527648, 5006.274090256892, 5074.188129899198, 5075.56857667095, 5085.949140508622, 5087.389513258358, 5088.974555127201, 5095.108356037678, 5110.233464980209, 4981.12830254704, 5031.77357018935, 5031.7987280509105, 5041.168207245837, 5052.797888084509, 4976.576990955737, 5039.244113383022, 5062.7215503146945, 5078.746062696924, 5139.254808052811, 4345.695801293942, 4361.171377438277, 4362.258416069276, 4366.890827729332, 4391.494978077498, 3772.7170462215445, 3816.6946644268946, 3825.158465137665, 3857.903552411156, 3888.9611103889165, 4398.142362239332, 4406.339049142654, 4414.823407063745, 4438.134563760036, 4449.354843547668, 5141.948580514222, 5144.819931302386, 5146.871328216825, 5147.459516750762, 5193.662411943212, 4438.28987362027, 4441.444819397575, 4442.911141777146, 4491.214037543482, 4497.707649002804, 4521.977390113094, 4526.547345265513, 4565.086982603492, 4567.109413664303, 4604.769761511911, 4768.403949506312, 4783.689060211075, 4793.592544633745, 4847.127517046965, 4847.5394837490385, 5056.696598204119, 5066.113430149783, 5071.323265728948, 5075.746212689424, 5135.38375394955, 4619.676622636383, 4706.670533062653, 4767.976160119178, 4773.9045219096115, 4823.493533179106, 4967.7019378837385, 5053.519915608012, 5065.873353166053, 5088.2876397305145, 5136.8202112925865, 5110.61670374716, 5136.512031357015, 5144.717040562805, 5152.871178220575, 5214.765335559985, 4833.879153021203, 5005.374328208974, 5013.273075403973, 5013.323201367914, 5015.3730783652045, 4826.58973987215, 4851.272309153655, 4866.05112003156, 4882.267659851051, 4927.802887884588, 4780.0917825614415, 4781.617470602413, 4787.257975013878, 4790.281457781221, 4842.104210721028, 4879.34128892595, 4879.438864530624, 4889.413270407432, 4900.656954013328, 4951.851444456672, 4731.313959475788, 4778.354922085613, 4863.513648635108, 4932.358793356841, 4940.876478087933], "value": 4655.601354539988, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.6530885542109, 564.1227890630068, 565.5758181363977, 566.575068698476, 573.7819628541154, 28057.158285251426, 38292.0, 39491.0, 39573.20853582927, 42961.0, 40684.79657601715, 41120.79439602805, 41203.381949270784, 41217.38173927394, 41218.0, 7146.928530714705, 7634.312911837934, 7742.303192712683, 7752.573608451533, 7793.610319484032, 7121.501494895333, 7330.376917961953, 7335.119785625732, 7342.77971660851, 9847.901520984806, 9742.561584728704, 10072.093511583957, 10105.494725263745, 10111.393316401003, 10113.089821916026, 10088.092071713545, 10152.79694406115, 10158.39049657023, 10173.440460774655, 10173.694789156338, 9991.150752186073, 10068.798624027479, 10075.949620251851, 10076.69769906904, 10077.54651040705, 37433.62566374329, 37831.62168378309, 37852.0, 37867.62132378696, 37912.43131353032, 2760.489309477733, 2786.5680819473014, 2816.985915070432, 3065.6781037990913, 3141.27750617357, 28457.430851382855, 28799.71200287991, 28893.27766805847, 29322.266943326373, 29932.401351973058, 3366.7138293245102, 3498.2828520153307, 3528.649725959143, 3534.29314137171, 3558.839852206657, 248215.0, 248507.0, 248904.0, 249076.0, 249327.0, 30422.391552168832, 50854.74572627113, 51048.48951510512, 51163.488365115874, 51196.0, 31442.842785785924, 33036.0, 33226.335473290885, 33446.8327658365, 33537.83231083829, 11499.252548584314, 11693.941530292293, 11696.88303116958, 11700.2979821211, 11704.122190835722, 9149.862752058725, 9233.769155771159, 9249.722508324761, 9276.443413395084, 9297.442153470678, 7121.857562848714, 7365.0, 7470.70117195317, 7807.68769249235, 7828.530288182614, 3050.80169788963, 3069.4321550513228, 3087.92280192997, 3090.2119959410206, 3098.752099832008, 836.9372297077743, 853.5049671190818, 893.3746377535751, 903.4940433357341, 931.1526510875207, 25752.356191094997, 25821.3544661385, 26195.0, 26420.471590568082, 27946.161615151577, 893.8346405914926, 928.5310917986509, 944.8818897637796, 954.5752140297501, 996.342414006752, 13763.0, 14724.263786810616, 14985.32566034531, 15205.61985950338, 15214.771778423332, 383379.0, 386221.0, 387199.28007199615, 387433.0, 387785.0, 41957.370639440436, 43089.13821723548, 47318.29022564664, 48071.278930815366, 48235.0, 1114.5653195253858, 1144.66804626659, 1146.2091157101572, 1146.380954284677, 1147.1912301827192, 26908.596371054842, 28014.859925700242, 28575.14274571766, 28802.711972880843, 29310.853445732635, 41018.58981410148, 41486.0, 41498.0, 41519.58480415278, 41533.1693366125, 316752.0, 371491.0, 383202.0, 383447.0, 604190.0, 36680.69873711379, 37142.07144821401, 37162.442563362114, 37478.0, 37946.810265948494, 40771.0, 40999.38500922429, 41187.0, 41282.587174127875, 41889.58110418857, 35782.105447364025, 35806.462903057, 35912.10219744423, 36113.638863612076, 36214.09464763296, 35579.46630800592, 35787.28425431529, 35915.28169436545, 36383.45424818577, 36558.0, 35251.64748352586, 35415.82292088523, 35637.0, 35691.0, 36031.81984090168, 31234.531482028244, 34714.82642586872, 35550.0, 35623.28753424865, 35911.82044089763, 24702.505949881262, 24855.87572062128, 25125.979843224995, 25186.24441266765, 25509.489810204064], "value": 16732.755429377685, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.6848993456217, 347.30192313449965, 350.68788777987595, 351.62903137190256, 375.44059351566176, 10765.515551800168, 14562.854371456282, 15044.548663540085, 16412.09733464659, 16621.91689041556, 16495.917520412382, 16860.156992150372, 17143.657126857455, 17186.312547498084, 17227.655446891054, 4764.094821983829, 4766.356541866847, 4873.22028475444, 4923.384576927884, 5015.147424937751, 3596.5504311961004, 4852.0, 4930.334404855353, 4934.062528119646, 4935.210366341386, 3767.8304476298495, 5508.091164957777, 5820.796272130477, 5849.619774714652, 5872.647641141545, 5700.059490184115, 5764.33710123337, 5839.649621022731, 5839.9124013139835, 5852.502537284336, 5205.765740541685, 5205.817796377147, 5209.218617207449, 5214.0, 5216.0, 20854.47863803402, 21187.258445954318, 22028.449288767748, 22069.0, 22073.0, 1947.4449781812257, 2086.2802333195177, 2255.4586899144088, 2379.0840526397287, 2455.4475243070165, 13666.11170273929, 14248.92875535626, 14293.0, 14297.356618952172, 15431.0, 1636.9754453683204, 1644.6710657868355, 1646.9208444320714, 1652.9834701652949, 1659.4772646616398, 76896.61551692261, 84218.57890710507, 84771.67511573722, 85456.57271713665, 86113.56943215306, 14713.926430367994, 15980.600484987968, 16435.26041328143, 16629.6674066518, 16999.66000680004, 29040.41919161605, 29117.85441072781, 29145.417091658048, 29229.269268268465, 29365.265868353035, 8096.595170241582, 8238.09380968093, 8278.213569710868, 8607.397482176313, 8647.524386158695, 6599.373059559334, 6705.865882682319, 6845.349691779273, 6905.1713794345, 6905.482088843357, 8085.63614637331, 8114.229148230908, 8428.704995325133, 8476.830463390697, 8639.222469977607, 3109.5335699645234, 3180.507021411685, 3327.4842399428126, 3328.301056778104, 3344.772294735592, 755.882838160075, 796.1759578835877, 808.0303635637259, 826.9007719073629, 886.2024178239636, 15953.361865525481, 16197.919010405109, 16742.330306787593, 16745.748813767805, 17379.65240695179, 830.7424698343533, 831.2767891933935, 854.6453221912836, 857.5240741388465, 858.3776761847736, 8329.95835020821, 9241.168294853496, 9615.278854085971, 9632.32573719847, 9705.708828735149, 393541.0, 398696.0, 406087.0, 406931.0, 407778.0, 79735.60132199418, 80074.0, 80609.59695201604, 81233.0, 84154.57922710347, 856.7472595584193, 866.3415803989013, 873.6854732296368, 887.6360692116201, 890.0609856601347, 14356.425742970165, 14953.626159346104, 15219.543413697607, 15261.923690381476, 15676.451324203597, 37072.44391334078, 37283.81358093192, 38194.618053819104, 38562.80718596389, 39036.219275614894, 49622.75188624034, 50687.0, 51463.22805158001, 52365.7381713089, 52792.0, 7440.590767507839, 7475.36459400957, 7567.091948966159, 7670.616469176407, 7708.922910771045, 7682.654280557276, 7920.881186782318, 7962.323202527618, 8647.783805404915, 8913.688020919237, 6888.862222755617, 7054.224035356209, 7215.314545118205, 7312.634368281459, 7856.528608283521, 7470.925290747023, 7513.812154696177, 7693.192214817587, 7883.0, 7979.241972012649, 7345.853082938418, 7664.233576642291, 7727.884081738666, 7779.688812447439, 7836.0, 10933.890661093605, 11106.389148596905, 11213.60752373663, 11237.775244495228, 11498.94250528742, 10320.43237621938, 10435.37387756737, 10490.632827850988, 10860.402677852793, 11507.769844602893], "value": 8694.183340197455, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1206.017096066706, 1308.3654427602612, 1358.9728205435893, 1379.4206433298014, 1662.8170901200865, 6568.310327415618, 7402.851942961144, 7731.729389471369, 7737.148913619498, 7756.379489640823, 7600.429967752413, 7667.616619169047, 7678.884816727754, 7725.0, 7736.922630773691, 2114.5030917734357, 2137.957240855182, 2144.9034793434334, 2149.9462513437134, 2151.709519214902, 2050.589882023594, 2086.2385229391316, 2113.17586141405, 2113.9788602114013, 2114.503091773432, 2063.319104695447, 2097.3603051069417, 2113.165299706615, 2116.798904104108, 2141.892905354734, 2030.2589554812535, 2032.1464984706458, 2034.0236686390467, 2034.8982550872545, 2038.398672391648, 2002.7096071069614, 2015.8992050397421, 2081.2819577245828, 2083.1354987680247, 2086.7495900491886, 7240.637968101574, 7487.0, 7691.769246922601, 7721.22787721229, 7742.806429839299, 4116.835326586933, 4237.258479766028, 4237.851675191354, 4240.936385954213, 4243.321068629036, 2610.85640289784, 2716.103685783706, 2764.3918337965615, 2768.307923019245, 2769.8892044318404, 949.4493193947494, 955.507913424587, 969.9806003879883, 977.4233202410541, 978.838491648877, 10375.221858360577, 10493.370397776082, 10533.525991330409, 10578.0, 10582.259241853073, 3738.2149748553106, 3889.572147063821, 3914.1388894443176, 3954.8813535593977, 4140.772257525805, 8275.213854683796, 8323.960629645775, 8371.58142092893, 8374.078851326349, 8377.036640786266, 4212.115455754326, 4225.281702110648, 4236.9364459533135, 4237.385579091015, 4240.872773816791, 3336.666333366644, 3441.913952151216, 3768.660820526166, 3949.19041596472, 3960.1881614269005, 3236.6277878044325, 3294.40700673881, 3368.1242876852193, 3369.1745522347182, 3408.7784294020807, 1008.9798204035878, 1010.7978404319166, 1019.0828254570946, 1020.2705065877933, 1037.984430233547, 654.306435178712, 681.4003676764415, 740.2930201657405, 756.092688773463, 785.0932173339802, 4901.803927842846, 4941.703497790144, 5171.870703232449, 5175.611829112831, 5178.741062946838, 375.1446701520538, 385.2353079137909, 387.37439035956726, 422.0946070678378, 424.4863714904938, 3745.5318085239346, 3831.6934645228316, 3902.726809123391, 3909.58949310324, 3910.2013073986286, 8705.390622656354, 9115.179633832855, 9136.406133601427, 9174.495402752913, 9190.678326258683, 9070.818583628423, 9074.319426043074, 9106.726798196065, 9109.863352049724, 9139.634414623473, 6894.0, 7178.533395329391, 7225.0, 7236.204017558064, 7237.7466788661095, 5349.304590403222, 5392.514673679388, 5392.568594512508, 5574.749136288796, 5638.126090455986, 8231.34149268045, 8421.95789021051, 8523.105073967392, 8642.141939483041, 8703.738887833375, 8585.270252028648, 8910.59902304385, 8936.955315223642, 8974.326925480484, 9049.411788233743, 5815.563832712479, 6031.457168854824, 6349.206349206349, 6591.967040164768, 6652.933470665232, 7073.681684324115, 7317.743878964211, 7321.816954576178, 7327.487075904635, 7539.246075392417, 6378.808635740749, 6389.7763578274535, 6472.15861937945, 6584.473242140713, 6625.602463852184, 6388.9041664376, 6439.098526206241, 6460.160179176677, 6489.772857949949, 6573.868522629617, 5988.700564971647, 6096.177016102769, 6105.45050945417, 6111.96944015277, 6112.72492737819, 4469.620082292911, 4469.932951005672, 4473.418455600881, 4553.2942393929, 4565.954340456686, 4450.933236001398, 4455.933161002523, 4478.843240486567, 4546.636269098531, 4601.309803529497], "value": 3880.673526325345, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.67235687494696, 291.5481004443113, 296.77000324748315, 297.8361900954475, 300.07576663875267, 4877.7804998775055, 4927.975360123194, 4928.753562321887, 4958.785097651072, 4964.32981547491, 4824.975875120619, 4913.80344786209, 4933.1367010773065, 4935.6545041847085, 4940.135476291653, 3620.981895090521, 3752.7748335099845, 3753.2118255166447, 3818.0, 3824.445455408964, 3820.9044773880737, 3823.1589050408866, 3831.9233615327676, 3857.3828187490008, 3869.438931354952, 3803.790791506466, 3824.2160357126863, 3831.865884694036, 3835.539735231776, 3851.326017946862, 3624.401973674354, 3739.6821270192067, 3759.1917737686254, 3810.8475660973527, 3821.0, 3381.4927760835826, 3389.711874490671, 3390.55922730046, 3390.9660903390895, 3394.913627639132, 4714.5521175488275, 4782.0, 4789.425268967711, 4795.544423279783, 4800.687955282895, 4038.414429907647, 4306.181825453185, 4390.034192477649, 4408.1624491346865, 4408.911821763579, 5047.570956468705, 5055.241713742931, 5059.974700126476, 5073.847784566469, 5125.282460455539, 3577.69063616154, 3584.390653588896, 3599.2621512590117, 3617.222297206087, 3630.9636903631153, 5046.520580544842, 5352.2506849041165, 5362.383325917531, 5380.730963451851, 5399.6490228135435, 3613.584437789635, 3660.5424321959754, 3665.2486240321205, 3668.908277293089, 3675.320065787784, 3714.0900479382185, 3724.162063535737, 3733.178700685845, 3755.286495565834, 3755.6619904208205, 4288.035192081757, 4299.914001719948, 4319.157764235943, 4322.891927701769, 4334.956650433455, 4853.95146048535, 5076.873078173076, 5124.9743751281, 5154.226865970135, 5190.325257716473, 1286.8841804237663, 1300.2003767682806, 1300.8243887075314, 1301.4859130643342, 1304.3804193008245, 790.4308897593723, 825.7068740597122, 828.7099515169642, 844.8225872566829, 845.045099038083, 4375.78121093944, 4388.670849686287, 4492.775361231991, 4540.409746732969, 4551.544845515488, 5217.556507696812, 5220.843374698765, 5246.950609878039, 5253.0, 5254.185601231815, 4495.213337665896, 4509.323601459807, 4513.7066090704, 4546.227141385973, 4554.294084416921, 2623.8556879371818, 2762.5441802102428, 2772.986135069312, 2778.6804517480346, 2780.7497325240824, 4917.729524876095, 4918.3360246367, 4939.5554400104165, 4941.827036053793, 4958.975205123951, 5121.820736274212, 5267.183586544092, 5366.785328586813, 5368.0, 5378.973105134528, 4879.463259041502, 4904.896398310351, 4906.386701662293, 4930.506949305112, 4953.950460495421, 5162.716050617252, 5174.27560141588, 5174.586033117417, 5203.635745497779, 5231.346081739783, 4996.527433276995, 5021.44764075944, 5125.154349532435, 5153.896922061613, 5155.0, 4922.803087876445, 4948.05986862495, 4967.850964471072, 4985.70085794839, 4995.5004499551205, 3643.908902277464, 3656.542932133483, 3661.132822260613, 3664.0, 3672.485851980671, 5023.146065169003, 5251.186066159751, 5295.046891559557, 5300.655457395257, 5301.231321458444, 5029.899402012013, 5032.27032080339, 5043.899122017612, 5073.568746656428, 5094.541491265803, 5046.142155833444, 5070.0, 5072.746362681925, 5083.72039537829, 5113.207452844815, 4756.643251756063, 5013.573846222966, 5018.297438358706, 5021.397432308147, 5035.622328325317, 4899.240617704261, 5034.0, 5134.178531434953, 5161.122609156378, 5174.586033117417, 4900.5099490052135, 5040.798368065236, 5046.545810877039, 5051.898962020666, 5056.292119103402], "value": 3829.6592800947897, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.3938213331336, 656.5371413153728, 660.102260925142, 672.2806596941275, 683.8871586188279, 12992.350382480874, 13502.729945401099, 13816.792748108774, 14010.0, 14020.22888741119, 14778.852211477883, 15013.099214047164, 15051.774223386657, 15066.773998390005, 15068.849311506881, 745.7613563659631, 766.7124828189429, 769.8229407236339, 770.0374531835206, 770.1951460723529, 598.377687205306, 614.8708771158063, 618.1068356225256, 620.0296535921291, 744.9553026818381, 769.4114002787871, 798.1579433697447, 799.0571126071234, 803.9879401808978, 804.4248362420868, 794.8847417124484, 795.1650766694976, 796.024869534821, 796.0924546017487, 796.2634563029233, 460.88477880529865, 583.0292562882795, 583.0525396231125, 583.151514546334, 583.1777194156219, 10409.219308551888, 13013.674658133528, 13433.19400835953, 13473.797893031613, 13985.650358741012, 12503.437345319482, 13181.86818131816, 13241.271730054843, 13362.33188340579, 13693.863061369359, 10506.474676266233, 10636.946815265874, 10728.195385346131, 10791.730206744816, 10860.511276992554, 3173.873045078194, 3184.5700830388064, 3184.8885289014775, 3254.3653987472453, 3355.2282974915906, 65551.67224163849, 66025.00962485519, 66734.3326566733, 68059.0, 68529.65735171342, 10052.698419047294, 10401.167906567456, 10509.264351495322, 10564.154867610572, 10703.250772445854, 18885.62228755417, 18928.905355473136, 19035.238590456505, 19071.0, 19077.236910523425, 6590.077389165521, 6688.598684078874, 6762.898556521656, 6915.271182204448, 6993.4055605274125, 3519.2081781598936, 3568.2685049564775, 3574.5531808523933, 3655.5065066216252, 3785.1861849702445, 2662.853543055112, 2757.5725762506845, 2770.473610014091, 2784.0673374419393, 2785.2062162284055, 472.8203282752532, 477.5487164629461, 479.8080767692881, 484.8375794109013, 491.83769356112396, 537.4974398937006, 538.3190264315643, 545.4164044472376, 548.3721139295563, 558.1906235957878, 8010.959945200237, 8197.139300373494, 8201.0, 8262.42163048581, 8429.578521073921, 562.3055526424862, 571.4085921071671, 571.4828080587075, 576.109910188751, 592.6589969620018, 3798.9362978366103, 3848.9037774055873, 3971.980140099281, 3972.3445631471063, 4015.2371049500503, 57482.0, 58337.0, 59854.40145598575, 106229.46885265679, 109227.45386273018, 89230.0, 89884.5505772467, 91114.0, 91799.0, 96012.03987960031, 81357.18642813496, 81828.59085704653, 82004.0, 82570.0, 82621.17378826135, 7693.346065584488, 7812.453128280966, 7942.682292708227, 8075.434719569808, 8094.716684915999, 46028.539714602426, 46199.76900115612, 46286.0, 46825.76587117043, 47044.0, 51540.0, 52450.0, 55247.0, 60330.396696032476, 62481.375186247555, 11179.496922638664, 11469.483873225558, 11561.595344162914, 11778.941105294418, 11910.0, 11896.76206475883, 11926.165168438127, 12040.638780836589, 12065.698357541132, 12097.33464659417, 10972.890271097187, 11394.145439092266, 12077.39613019363, 12400.441980110736, 13142.80285795733, 10711.94644026775, 10776.622818201326, 10865.511052002521, 11447.656570302905, 11535.76928461443, 10965.56137754481, 11248.212625116163, 11263.943680281545, 12790.808137878126, 12944.15862968904, 9306.860397094184, 9321.30090243221, 10276.537555810157, 10406.791864162826, 10667.195596658008, 9215.354925155174, 10306.896931030593, 10400.739981500523, 10426.843597346198, 10511.94744026275], "value": 6478.290638396179, "unit": "score"}], "name": "dromaeo_css", "value": 6344.417502975885, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 116.0, 116.0, 116.0, 115.0, 114.0, 116.0, 114.0, 116.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 124.0, 128.0, 155.0, 130.0, 127.0, 124.0, 127.0, 170.0, 130.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 163.0, 162.0, 161.0, 163.0, 160.0, 161.0, 162.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 78.0, 112.0, 76.0, 78.0, 88.0, 76.0, 100.0, 75.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 139.0, 84.0, 85.0, 84.0, 117.0, 83.0, 82.0, 124.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 118.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 145.0, 147.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 62.0, 65.0, 65.0, 65.0, 65.0, 65.0, 72.0], "value": 64.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 67.0, 66.0, 65.0, 66.0, 67.0, 66.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 82.0, 83.0, 86.0, 85.0, 88.0, 86.0, 82.0, 82.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 142.0, 131.0, 132.0, 145.0, 142.0, 134.0, 132.0, 180.0, 140.0], "value": 142.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 175.0, 171.0, 172.0, 177.0, 175.0, 171.0, 172.0, 169.0, 170.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 69.0, 69.0, 70.0, 69.0, 72.0, 73.0, 69.0, 68.0], "value": 70.2, "unit": "score"}], "name": "kraken", "value": 1553.8, "alertThreshold": 2.0}]} 05:51:56 INFO - Return code: 0 05:51:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:51:56 INFO - # TBPL SUCCESS # 05:51:56 INFO - Running post-action listener: _resource_record_post_action 05:51:56 INFO - [mozharness: 2016-09-16 12:51:56.570486Z] Finished run-tests step (success) 05:51:56 INFO - Running post-run listener: _resource_record_post_run 05:51:56 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 4096; Write bytes: 410198016; Read time: 16; Write time: 234048 05:51:56 INFO - TinderboxPrint: CPU usage
13.1% 05:51:56 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 05:51:56 INFO - TinderboxPrint: I/O write bytes / time
410,198,016 / 234,048 05:51:56 INFO - TinderboxPrint: CPU idle
8,349.2 (86.9%) 05:51:56 INFO - TinderboxPrint: CPU user
1,203.0 (12.5%) 05:51:56 INFO - TinderboxPrint: Swap in / out
0 / 0 05:51:56 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115732480; Read time: 0; Write time: 85932 05:51:56 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294129664; Read time: 16; Write time: 148084 05:51:57 INFO - Running post-run listener: _upload_blobber_files 05:51:57 INFO - Blob upload gear active. 05:51:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:51:57 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 05:51:57 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:51:57 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:51:57 INFO - (blobuploader) - INFO - Open directory for files ... 05:51:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:51:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:51:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:58 INFO - (blobuploader) - INFO - Done attempting. 05:51:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:51:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:51:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:59 INFO - (blobuploader) - INFO - Done attempting. 05:51:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:51:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:52:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:02 INFO - (blobuploader) - INFO - Done attempting. 05:52:02 INFO - (blobuploader) - INFO - Iteration through files over. 05:52:02 INFO - Return code: 0 05:52:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:52:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:52:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/371171dfcb7cac73be01c2bc76bb75655a3df77cbc5fee5d63378a136285111218d24f150b24ad7c7cf6c1bdb5a1f1bcbeb9e780be9f088177a5eccb15b64d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4ed36aea9571719cd17b8396a4d12da178e338371b282819b5a10774d3dece02b8a1b284e96f997a1bb978d26a5177c682b8facb4b3ddb988f233c2ccbdb90f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d879fec4dcfd40c282a2012cbe048c772e0ba6dc298219d37edfd0d31c7bd4297e88c21d04b0e223e8eca7d00221c079779525370db22d6b998c3f27fe475d6b"} 05:52:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:52:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:52:02 INFO - Contents: 05:52:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/371171dfcb7cac73be01c2bc76bb75655a3df77cbc5fee5d63378a136285111218d24f150b24ad7c7cf6c1bdb5a1f1bcbeb9e780be9f088177a5eccb15b64d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4ed36aea9571719cd17b8396a4d12da178e338371b282819b5a10774d3dece02b8a1b284e96f997a1bb978d26a5177c682b8facb4b3ddb988f233c2ccbdb90f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d879fec4dcfd40c282a2012cbe048c772e0ba6dc298219d37edfd0d31c7bd4297e88c21d04b0e223e8eca7d00221c079779525370db22d6b998c3f27fe475d6b"} 05:52:02 INFO - Running post-run listener: copy_logs_to_upload_dir 05:52:02 INFO - Copying logs to upload dir... 05:52:02 INFO - mkdir: /builds/slave/test/build/upload/logs 05:52:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.638692 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-09-16 05:52:02.661739) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 05:52:02.665414) ========= 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=7de08804244eb36560b44971000001c3-1474028783.291941-547746672 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/371171dfcb7cac73be01c2bc76bb75655a3df77cbc5fee5d63378a136285111218d24f150b24ad7c7cf6c1bdb5a1f1bcbeb9e780be9f088177a5eccb15b64d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4ed36aea9571719cd17b8396a4d12da178e338371b282819b5a10774d3dece02b8a1b284e96f997a1bb978d26a5177c682b8facb4b3ddb988f233c2ccbdb90f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d879fec4dcfd40c282a2012cbe048c772e0ba6dc298219d37edfd0d31c7bd4297e88c21d04b0e223e8eca7d00221c079779525370db22d6b998c3f27fe475d6b"} build_url:https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011340 build_url: 'https://queue.taskcluster.net/v1/task/f-NaBFC2TjuUtAJi-gcD0Q/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/Firefox-Non-PGO/sha512/371171dfcb7cac73be01c2bc76bb75655a3df77cbc5fee5d63378a136285111218d24f150b24ad7c7cf6c1bdb5a1f1bcbeb9e780be9f088177a5eccb15b64d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4ed36aea9571719cd17b8396a4d12da178e338371b282819b5a10774d3dece02b8a1b284e96f997a1bb978d26a5177c682b8facb4b3ddb988f233c2ccbdb90f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d879fec4dcfd40c282a2012cbe048c772e0ba6dc298219d37edfd0d31c7bd4297e88c21d04b0e223e8eca7d00221c079779525370db22d6b998c3f27fe475d6b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 05:52:02.693134) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 05:52:02.693763) ========= 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=7de08804244eb36560b44971000001c3-1474028783.291941-547746672 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004387 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 05:52:02.776975) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 05:52:02.777398) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 05:52:02.777723) ========= ========= Total master_lag: 0.21 =========