builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-028 starttime: 1474125098.57 results: success (0) buildid: 20160917075418 builduid: 6202540e228645689f8b4d0f708f8569 revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:38.567360) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:38.567813) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:38.568085) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:38.583015) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:38.583382) ========= 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=836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 08:11:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.0M=0s 2016-09-17 08:11:39 (46.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108326 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:38.705487) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:38.705813) ========= 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=836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015773 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:38.755471) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 08:11:38.755830) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b401cb17167b34c362eb819259effbb3c0979f59 --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 b401cb17167b34c362eb819259effbb3c0979f59 --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=836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 08:11:39,783 truncating revision to first 12 chars 2016-09-17 08:11:39,783 Setting DEBUG logging. 2016-09-17 08:11:39,783 attempt 1/10 2016-09-17 08:11:39,783 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b401cb17167b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 08:11:40,700 unpacking tar archive at: mozilla-central-b401cb17167b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.230692 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 08:11:40.000376) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:40.000706) ========= script_repo_revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:40.001102) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:40.001375) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:40.012468) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-09-17 08:11:40.012868) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:11:41 INFO - MultiFileLogger online at 20160917 08:11:41 in /builds/slave/test 08:11:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:11:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:11:41 INFO - {'append_to_log': False, 08:11:41 INFO - 'base_work_dir': '/builds/slave/test', 08:11:41 INFO - 'blob_upload_branch': 'Firefox', 08:11:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:11:41 INFO - 'branch': 'Firefox', 08:11:41 INFO - 'buildbot_json_path': 'buildprops.json', 08:11:41 INFO - 'config_files': ('talos/linux_config.py',), 08:11:41 INFO - 'default_actions': ('clobber', 08:11:41 INFO - 'read-buildbot-config', 08:11:41 INFO - 'download-and-extract', 08:11:41 INFO - 'populate-webroot', 08:11:41 INFO - 'create-virtualenv', 08:11:41 INFO - 'install', 08:11:41 INFO - 'run-tests'), 08:11:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:11:41 INFO - 'download_minidump_stackwalk': True, 08:11:41 INFO - 'download_symbols': 'ondemand', 08:11:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:11:41 INFO - 'tooltool.py': '/tools/tooltool.py', 08:11:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:11:41 INFO - '/tools/misc-python/virtualenv.py')}, 08:11:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:11:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:11:41 INFO - 'installer_path': 'installer.exe', 08:11:41 INFO - 'log_level': 'info', 08:11:41 INFO - 'log_name': 'talos', 08:11:41 INFO - 'log_to_console': True, 08:11:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:11:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:11:41 INFO - 'opt_config_files': (), 08:11:41 INFO - 'pip_index': False, 08:11:41 INFO - 'sps_profile': False, 08:11:41 INFO - 'sps_profile_interval': 0, 08:11:41 INFO - 'suite': 'dromaeojs', 08:11:41 INFO - 'system_bits': '32', 08:11:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:11:41 INFO - 'title': 'talos-linux64-ix-028', 08:11:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:11:41 INFO - 'use_talos_json': True, 08:11:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:11:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:11:41 INFO - 'work_dir': 'build'} 08:11:41 INFO - [mozharness: 2016-09-17 15:11:41.132074Z] Running clobber step. 08:11:41 INFO - Running pre-action listener: _resource_record_pre_action 08:11:41 INFO - Running main action method: clobber 08:11:41 INFO - rmtree: /builds/slave/test/build 08:11:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:11:41 INFO - Running post-action listener: _resource_record_post_action 08:11:41 INFO - [mozharness: 2016-09-17 15:11:41.593050Z] Finished clobber step (success) 08:11:41 INFO - [mozharness: 2016-09-17 15:11:41.593152Z] Running read-buildbot-config step. 08:11:41 INFO - Running pre-action listener: _resource_record_pre_action 08:11:41 INFO - Running main action method: read_buildbot_config 08:11:41 INFO - Using buildbot properties: 08:11:41 INFO - { 08:11:41 INFO - "project": "", 08:11:41 INFO - "product": "firefox", 08:11:41 INFO - "script_repo_revision": "production", 08:11:41 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:11:41 INFO - "repository": "", 08:11:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 08:11:41 INFO - "buildid": "20160917075418", 08:11:41 INFO - "stage_platform": "linux64-pgo", 08:11:41 INFO - "basedir": "/builds/slave/test", 08:11:41 INFO - "buildnumber": 579, 08:11:41 INFO - "platform": "ubuntu64_hw", 08:11:41 INFO - "mozci_request": { 08:11:41 INFO - "priority": -1, 08:11:41 INFO - "type": "trigger_all_talos_jobs", 08:11:41 INFO - "times": 6 08:11:41 INFO - }, 08:11:41 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:11:41 INFO - "slavebuilddir": "test", 08:11:41 INFO - "branch": "mozilla-central", 08:11:41 INFO - "revision": "b401cb17167b34c362eb819259effbb3c0979f59", 08:11:41 INFO - "slavename": "talos-linux64-ix-028", 08:11:41 INFO - "builduid": "6202540e228645689f8b4d0f708f8569", 08:11:41 INFO - "repo_path": "mozilla-central" 08:11:41 INFO - } 08:11:41 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 08:11:41 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.test_packages.json. 08:11:41 INFO - Running post-action listener: _resource_record_post_action 08:11:41 INFO - [mozharness: 2016-09-17 15:11:41.595930Z] Finished read-buildbot-config step (success) 08:11:41 INFO - [mozharness: 2016-09-17 15:11:41.596020Z] Running download-and-extract step. 08:11:41 INFO - Running pre-action listener: _resource_record_pre_action 08:11:41 INFO - Running main action method: download_and_extract 08:11:41 INFO - mkdir: /builds/slave/test/build/tests 08:11: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')]} 08:11:41 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:11:41 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:11:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:11:41 INFO - Downloaded 1361 bytes. 08:11:41 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:11:41 INFO - Using the following test package requirements: 08:11:41 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:11:41 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:11:41 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:11:41 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:41 INFO - u'jsshell-linux-x86_64.zip'], 08:11:41 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:11:41 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:11:41 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:11:41 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:11:41 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:11:41 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:11:41 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:11:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:11:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:11:41 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:11:41 INFO - Expected file size: 21936347 08:11:46 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:11:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:11:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:11:46 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:11:46 INFO - Expected file size: 21936347 08:11:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 08:11:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:11:50 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 08:11:50 INFO - Expected file size: 14414048 08:11: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')]} 08:11:51 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:11:51 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:11:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:11:51 INFO - Downloaded 59535581 bytes. 08:11:51 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:11:51 INFO - mkdir: /builds/slave/test/properties 08:11:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:11:51 INFO - Writing to file /builds/slave/test/properties/build_url 08:11:51 INFO - Contents: 08:11:51 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:11:52 INFO - Running post-action listener: _resource_record_post_action 08:11:52 INFO - Running post-action listener: set_extra_try_arguments 08:11:52 INFO - [mozharness: 2016-09-17 15:11:52.243111Z] Finished download-and-extract step (success) 08:11:52 INFO - [mozharness: 2016-09-17 15:11:52.243341Z] Running populate-webroot step. 08:11:52 INFO - Running pre-action listener: _resource_record_pre_action 08:11:52 INFO - Running main action method: populate_webroot 08:11:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:11:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:11:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:11:52 INFO - '--tppagecycles', 08:11:52 INFO - '7'], 08:11:52 INFO - 'tests': ['tsvgm']}}, 08:11:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:11:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:11:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:11:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:11:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:11:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:11:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:52 INFO - 'talos_options': ['--disable-e10s'], 08:11:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:11:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:11:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:52 INFO - 'talos_options': ['--disable-e10s'], 08:11:52 INFO - 'tests': ['damp', 'tps']}, 08:11:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:52 INFO - 'tests': ['damp', 'tps']}, 08:11:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:11:52 INFO - 'tests': ['dromaeo_dom']}, 08:11:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:11:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:11:52 INFO - 'tests': ['basic_compositor_video']}, 08:11:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:11:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:11:52 INFO - 'tests': ['a11yr', 08:11:52 INFO - 'ts_paint', 08:11:52 INFO - 'tpaint', 08:11:52 INFO - 'sessionrestore', 08:11:52 INFO - 'sessionrestore_no_auto_restore', 08:11:52 INFO - 'tabpaint']}, 08:11:52 INFO - 'other-e10s': {'tests': ['a11yr', 08:11:52 INFO - 'ts_paint', 08:11:52 INFO - 'tpaint', 08:11:52 INFO - 'sessionrestore', 08:11:52 INFO - 'sessionrestore_no_auto_restore', 08:11:52 INFO - 'tabpaint']}, 08:11:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:11:52 INFO - 'ts_paint', 08:11:52 INFO - 'tpaint', 08:11:52 INFO - 'sessionrestore', 08:11:52 INFO - 'sessionrestore_no_auto_restore', 08:11:52 INFO - 'tabpaint']}, 08:11:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:11:52 INFO - 'ts_paint', 08:11:52 INFO - 'tpaint', 08:11:52 INFO - 'sessionrestore', 08:11:52 INFO - 'sessionrestore_no_auto_restore', 08:11:52 INFO - 'tabpaint']}, 08:11:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:11:52 INFO - 'tests': ['a11yr', 08:11:52 INFO - 'ts_paint', 08:11:52 INFO - 'tpaint', 08:11:52 INFO - 'sessionrestore', 08:11:52 INFO - 'sessionrestore_no_auto_restore', 08:11:52 INFO - 'tabpaint']}, 08:11:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:11:52 INFO - 'tests': ['a11yr', 08:11:52 INFO - 'ts_paint', 08:11:52 INFO - 'tpaint', 08:11:52 INFO - 'sessionrestore', 08:11:52 INFO - 'sessionrestore_no_auto_restore', 08:11:52 INFO - 'tabpaint']}, 08:11:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:11:52 INFO - 'tests': ['tsvgx', 08:11:52 INFO - 'tsvgr_opacity', 08:11:52 INFO - 'tart', 08:11:52 INFO - 'tscrollx', 08:11:52 INFO - 'cart']}, 08:11:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:11:52 INFO - 'tsvgr_opacity', 08:11:52 INFO - 'tart', 08:11:52 INFO - 'tscrollx', 08:11:52 INFO - 'cart']}, 08:11:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:52 INFO - 'talos_options': ['--disable-e10s'], 08:11:52 INFO - 'tests': ['tp5o']}, 08:11:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:52 INFO - 'tests': ['tp5o']}, 08:11:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:52 INFO - 'talos_options': ['--disable-e10s', 08:11:52 INFO - '--xperf_path', 08:11:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:11:52 INFO - 'tests': ['tp5n']}, 08:11:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:52 INFO - 'talos_options': ['--xperf_path', 08:11:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:11:52 INFO - 'tests': ['tp5n']}}, 08:11:52 INFO - 'talos.zip': {'path': '', 08:11:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:11:52 INFO - Running post-action listener: _resource_record_post_action 08:11:52 INFO - [mozharness: 2016-09-17 15:11:52.263521Z] Finished populate-webroot step (success) 08:11:52 INFO - [mozharness: 2016-09-17 15:11:52.263610Z] Running create-virtualenv step. 08:11:52 INFO - Running pre-action listener: _resource_record_pre_action 08:11:52 INFO - Running main action method: create_virtualenv 08:11:52 INFO - Creating virtualenv /builds/slave/test/build/venv 08:11:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:11:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:11:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:11:52 INFO - Using real prefix '/usr' 08:11:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:11:52 INFO - Installing distribute.............................................................................................................................................................................................done. 08:11:54 INFO - Installing pip.................done. 08:11:54 INFO - Return code: 0 08:11:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:54 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 0x7ffb6fcd3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c9790>, '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 0x20ca690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cab20>, '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': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:11:54 INFO - Using env: {'DISPLAY': ':0', 08:11:54 INFO - 'HOME': '/home/cltbld', 08:11:54 INFO - 'LANG': 'en_US.UTF-8', 08:11:54 INFO - 'LANGUAGE': 'en_US:en', 08:11:54 INFO - 'LOGNAME': 'cltbld', 08:11:54 INFO - 'MAIL': '/var/mail/cltbld', 08:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:54 INFO - 'MOZ_NO_REMOTE': '1', 08:11:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:54 INFO - 'NO_EM_RESTART': '1', 08:11:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:54 INFO - 'PWD': '/builds/slave/test', 08:11:54 INFO - 'SHELL': '/bin/bash', 08:11:54 INFO - 'SHLVL': '1', 08:11:54 INFO - 'TERM': 'linux', 08:11:54 INFO - 'TMOUT': '86400', 08:11:54 INFO - 'USER': 'cltbld', 08:11:54 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 08:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:54 INFO - '_': '/tools/buildbot/bin/python'} 08:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:11:55 INFO - Cleaning up... 08:11:55 INFO - Return code: 0 08:11:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:55 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 0x7ffb6fcd3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c9790>, '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 0x20ca690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cab20>, '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': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:11:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:11:55 INFO - Using env: {'DISPLAY': ':0', 08:11:55 INFO - 'HOME': '/home/cltbld', 08:11:55 INFO - 'LANG': 'en_US.UTF-8', 08:11:55 INFO - 'LANGUAGE': 'en_US:en', 08:11:55 INFO - 'LOGNAME': 'cltbld', 08:11:55 INFO - 'MAIL': '/var/mail/cltbld', 08:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:55 INFO - 'MOZ_NO_REMOTE': '1', 08:11:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:55 INFO - 'NO_EM_RESTART': '1', 08:11:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:55 INFO - 'PWD': '/builds/slave/test', 08:11:55 INFO - 'SHELL': '/bin/bash', 08:11:55 INFO - 'SHLVL': '1', 08:11:55 INFO - 'TERM': 'linux', 08:11:55 INFO - 'TMOUT': '86400', 08:11:55 INFO - 'USER': 'cltbld', 08:11:55 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 08:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:55 INFO - '_': '/tools/buildbot/bin/python'} 08:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:55 INFO - Downloading/unpacking psutil>=3.1.1 08:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:11:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:11:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:11:57 INFO - Installing collected packages: psutil 08:11:57 INFO - Running setup.py install for psutil 08:11:57 INFO - building 'psutil._psutil_linux' extension 08:11:57 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:11:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:11:57 INFO - building 'psutil._psutil_posix' extension 08:11:57 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:11:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:11:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:11:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:11:57 INFO - Successfully installed psutil 08:11:57 INFO - Cleaning up... 08:11:58 INFO - Return code: 0 08:11:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:58 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 0x7ffb6fcd3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c9790>, '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 0x20ca690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cab20>, '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': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:11:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:11:58 INFO - Using env: {'DISPLAY': ':0', 08:11:58 INFO - 'HOME': '/home/cltbld', 08:11:58 INFO - 'LANG': 'en_US.UTF-8', 08:11:58 INFO - 'LANGUAGE': 'en_US:en', 08:11:58 INFO - 'LOGNAME': 'cltbld', 08:11:58 INFO - 'MAIL': '/var/mail/cltbld', 08:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:58 INFO - 'MOZ_NO_REMOTE': '1', 08:11:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:58 INFO - 'NO_EM_RESTART': '1', 08:11:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:58 INFO - 'PWD': '/builds/slave/test', 08:11:58 INFO - 'SHELL': '/bin/bash', 08:11:58 INFO - 'SHLVL': '1', 08:11:58 INFO - 'TERM': 'linux', 08:11:58 INFO - 'TMOUT': '86400', 08:11:58 INFO - 'USER': 'cltbld', 08:11:58 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 08:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:58 INFO - '_': '/tools/buildbot/bin/python'} 08:11:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:12:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:12:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:12:00 INFO - Installing collected packages: mozsystemmonitor 08:12:00 INFO - Running setup.py install for mozsystemmonitor 08:12:00 INFO - Successfully installed mozsystemmonitor 08:12:00 INFO - Cleaning up... 08:12:00 INFO - Return code: 0 08:12:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6fcd3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c9790>, '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 0x20ca690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cab20>, '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': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:12:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:12:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:12:00 INFO - Using env: {'DISPLAY': ':0', 08:12:00 INFO - 'HOME': '/home/cltbld', 08:12:00 INFO - 'LANG': 'en_US.UTF-8', 08:12:00 INFO - 'LANGUAGE': 'en_US:en', 08:12:00 INFO - 'LOGNAME': 'cltbld', 08:12:00 INFO - 'MAIL': '/var/mail/cltbld', 08:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:00 INFO - 'MOZ_NO_REMOTE': '1', 08:12:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:00 INFO - 'NO_EM_RESTART': '1', 08:12:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:00 INFO - 'PWD': '/builds/slave/test', 08:12:00 INFO - 'SHELL': '/bin/bash', 08:12:00 INFO - 'SHLVL': '1', 08:12:00 INFO - 'TERM': 'linux', 08:12:00 INFO - 'TMOUT': '86400', 08:12:00 INFO - 'USER': 'cltbld', 08:12:00 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 08:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:00 INFO - '_': '/tools/buildbot/bin/python'} 08:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:00 INFO - Downloading/unpacking jsonschema==2.5.1 08:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:12:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:02 INFO - Downloading functools32-3.2.3-2.tar.gz 08:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:12:02 INFO - warning: no files found matching '*.txt' 08:12:02 INFO - no previously-included directories found matching 'build' 08:12:02 INFO - no previously-included directories found matching 'dist' 08:12:02 INFO - no previously-included directories found matching '.git*' 08:12:02 INFO - Installing collected packages: jsonschema, functools32 08:12:02 INFO - Running setup.py install for functools32 08:12:02 INFO - warning: no files found matching '*.txt' 08:12:02 INFO - no previously-included directories found matching 'build' 08:12:02 INFO - no previously-included directories found matching 'dist' 08:12:02 INFO - no previously-included directories found matching '.git*' 08:12:02 INFO - Successfully installed jsonschema functools32 08:12:02 INFO - Cleaning up... 08:12:02 INFO - Return code: 0 08:12:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:02 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 0x7ffb6fcd3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c9790>, '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 0x20ca690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cab20>, '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': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:12:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:12:02 INFO - Using env: {'DISPLAY': ':0', 08:12:02 INFO - 'HOME': '/home/cltbld', 08:12:02 INFO - 'LANG': 'en_US.UTF-8', 08:12:02 INFO - 'LANGUAGE': 'en_US:en', 08:12:02 INFO - 'LOGNAME': 'cltbld', 08:12:02 INFO - 'MAIL': '/var/mail/cltbld', 08:12:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:02 INFO - 'MOZ_NO_REMOTE': '1', 08:12:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:02 INFO - 'NO_EM_RESTART': '1', 08:12:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:02 INFO - 'PWD': '/builds/slave/test', 08:12:02 INFO - 'SHELL': '/bin/bash', 08:12:02 INFO - 'SHLVL': '1', 08:12:02 INFO - 'TERM': 'linux', 08:12:02 INFO - 'TMOUT': '86400', 08:12:02 INFO - 'USER': 'cltbld', 08:12:02 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 08:12:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:02 INFO - '_': '/tools/buildbot/bin/python'} 08:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:12:03 INFO - Cleaning up... 08:12:03 INFO - Return code: 0 08:12:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:03 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 0x7ffb6fcd3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c9790>, '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 0x20ca690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cab20>, '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': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:12:03 INFO - Using env: {'DISPLAY': ':0', 08:12:03 INFO - 'HOME': '/home/cltbld', 08:12:03 INFO - 'LANG': 'en_US.UTF-8', 08:12:03 INFO - 'LANGUAGE': 'en_US:en', 08:12:03 INFO - 'LOGNAME': 'cltbld', 08:12:03 INFO - 'MAIL': '/var/mail/cltbld', 08:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:03 INFO - 'MOZ_NO_REMOTE': '1', 08:12:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:03 INFO - 'NO_EM_RESTART': '1', 08:12:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:03 INFO - 'PWD': '/builds/slave/test', 08:12:03 INFO - 'SHELL': '/bin/bash', 08:12:03 INFO - 'SHLVL': '1', 08:12:03 INFO - 'TERM': 'linux', 08:12:03 INFO - 'TMOUT': '86400', 08:12:03 INFO - 'USER': 'cltbld', 08:12:03 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 08:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:03 INFO - '_': '/tools/buildbot/bin/python'} 08:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:03 INFO - Downloading/unpacking blobuploader==1.2.4 08:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:05 INFO - Downloading blobuploader-1.2.4.tar.gz 08:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:12:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:12:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:05 INFO - Downloading docopt-0.6.1.tar.gz 08:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:12:05 INFO - Installing collected packages: blobuploader, requests, docopt 08:12:05 INFO - Running setup.py install for blobuploader 08:12:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:12:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:12:06 INFO - Running setup.py install for requests 08:12:06 INFO - Running setup.py install for docopt 08:12:06 INFO - Successfully installed blobuploader requests docopt 08:12:06 INFO - Cleaning up... 08:12:06 INFO - Return code: 0 08:12:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:06 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 0x7ffb6fcd3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c9790>, '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 0x20ca690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cab20>, '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': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:12:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:12:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:12:06 INFO - Using env: {'DISPLAY': ':0', 08:12:06 INFO - 'HOME': '/home/cltbld', 08:12:06 INFO - 'LANG': 'en_US.UTF-8', 08:12:06 INFO - 'LANGUAGE': 'en_US:en', 08:12:06 INFO - 'LOGNAME': 'cltbld', 08:12:06 INFO - 'MAIL': '/var/mail/cltbld', 08:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:06 INFO - 'MOZ_NO_REMOTE': '1', 08:12:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:06 INFO - 'NO_EM_RESTART': '1', 08:12:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:06 INFO - 'PWD': '/builds/slave/test', 08:12:06 INFO - 'SHELL': '/bin/bash', 08:12:06 INFO - 'SHLVL': '1', 08:12:06 INFO - 'TERM': 'linux', 08:12:06 INFO - 'TMOUT': '86400', 08:12:06 INFO - 'USER': 'cltbld', 08:12:06 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 08:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:06 INFO - '_': '/tools/buildbot/bin/python'} 08:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:12:06 INFO - Running setup.py (path:/tmp/pip-hr6Euo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:12:06 INFO - Running setup.py (path:/tmp/pip-wrQjc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:12:06 INFO - Running setup.py (path:/tmp/pip-JpqfqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:12:07 INFO - Running setup.py (path:/tmp/pip-gHC0vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:12:07 INFO - Running setup.py (path:/tmp/pip-TeFzp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:12:07 INFO - Running setup.py (path:/tmp/pip-LWwp53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:12:07 INFO - Running setup.py (path:/tmp/pip-8eapDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:12:07 INFO - Running setup.py (path:/tmp/pip-t8_jUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:12:07 INFO - Running setup.py (path:/tmp/pip-wu8lb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:12:07 INFO - Running setup.py (path:/tmp/pip-rOaf8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:12:07 INFO - Running setup.py (path:/tmp/pip-rbltDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:12:07 INFO - Running setup.py (path:/tmp/pip-0GGywq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:12:07 INFO - Running setup.py (path:/tmp/pip-VcX5F1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:12:07 INFO - Running setup.py (path:/tmp/pip-Xhc3FS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:07 INFO - Running setup.py (path:/tmp/pip-q1SPwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:12:07 INFO - Running setup.py (path:/tmp/pip-DXkC1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:12:07 INFO - Running setup.py (path:/tmp/pip-RQopME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:12:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:12:08 INFO - Running setup.py install for manifestparser 08:12:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:12:08 INFO - Running setup.py install for mozcrash 08:12:08 INFO - Running setup.py install for mozdebug 08:12:08 INFO - Running setup.py install for mozdevice 08:12:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:12:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:12:08 INFO - Running setup.py install for mozfile 08:12:08 INFO - Running setup.py install for mozhttpd 08:12:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:12:08 INFO - Running setup.py install for mozinfo 08:12:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:12:08 INFO - Running setup.py install for mozInstall 08:12:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:12:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:12:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:12:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:12:08 INFO - Running setup.py install for mozleak 08:12:08 INFO - Running setup.py install for mozlog 08:12:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:12:09 INFO - Running setup.py install for moznetwork 08:12:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:12:09 INFO - Running setup.py install for mozprocess 08:12:09 INFO - Running setup.py install for mozprofile 08:12:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:12:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:12:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:12:09 INFO - Running setup.py install for mozrunner 08:12:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:12:09 INFO - Running setup.py install for mozscreenshot 08:12:09 INFO - Running setup.py install for moztest 08:12:09 INFO - Running setup.py install for mozversion 08:12:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:12:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:12:09 INFO - Cleaning up... 08:12:09 INFO - Return code: 0 08:12:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:09 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 0x7ffb6fcd3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c9790>, '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 0x20ca690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cab20>, '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': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:12:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:12:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:12:09 INFO - Using env: {'DISPLAY': ':0', 08:12:09 INFO - 'HOME': '/home/cltbld', 08:12:09 INFO - 'LANG': 'en_US.UTF-8', 08:12:09 INFO - 'LANGUAGE': 'en_US:en', 08:12:09 INFO - 'LOGNAME': 'cltbld', 08:12:09 INFO - 'MAIL': '/var/mail/cltbld', 08:12:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:09 INFO - 'MOZ_NO_REMOTE': '1', 08:12:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:09 INFO - 'NO_EM_RESTART': '1', 08:12:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:09 INFO - 'PWD': '/builds/slave/test', 08:12:09 INFO - 'SHELL': '/bin/bash', 08:12:09 INFO - 'SHLVL': '1', 08:12:09 INFO - 'TERM': 'linux', 08:12:09 INFO - 'TMOUT': '86400', 08:12:09 INFO - 'USER': 'cltbld', 08:12:09 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 08:12:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:09 INFO - '_': '/tools/buildbot/bin/python'} 08:12:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:12:10 INFO - Running setup.py (path:/tmp/pip-6t5qDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:12:10 INFO - Running setup.py (path:/tmp/pip-Zb4U9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:12:10 INFO - Running setup.py (path:/tmp/pip-Ls_Bd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:12:10 INFO - Running setup.py (path:/tmp/pip-U4dds3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:12:10 INFO - Running setup.py (path:/tmp/pip-2sfRxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:12:10 INFO - Running setup.py (path:/tmp/pip-D6xq3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:12:10 INFO - Running setup.py (path:/tmp/pip-mRcVVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:12:10 INFO - Running setup.py (path:/tmp/pip-dRrO4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:12:10 INFO - Running setup.py (path:/tmp/pip-0JG0Mu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:12:10 INFO - Running setup.py (path:/tmp/pip-vo2cOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:12:10 INFO - Running setup.py (path:/tmp/pip-ccXUed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:12:10 INFO - Running setup.py (path:/tmp/pip-8hFJEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:12:10 INFO - Running setup.py (path:/tmp/pip-ZLdKVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:12:10 INFO - Running setup.py (path:/tmp/pip-S5UMWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:11 INFO - Running setup.py (path:/tmp/pip-QZhdoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:12:11 INFO - Running setup.py (path:/tmp/pip-QGISAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:12:11 INFO - Running setup.py (path:/tmp/pip-yloiXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:12:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:12:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:12:13 INFO - Downloading blessings-1.6.tar.gz 08:12:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:12:13 INFO - Installing collected packages: blessings 08:12:13 INFO - Running setup.py install for blessings 08:12:13 INFO - Successfully installed blessings 08:12:13 INFO - Cleaning up... 08:12:13 INFO - Return code: 0 08:12:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:12:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:12:13 INFO - Reading from file tmpfile_stdout 08:12:13 INFO - Current package versions: 08:12:13 INFO - argparse == 1.2.1 08:12:13 INFO - blessings == 1.6 08:12:13 INFO - blobuploader == 1.2.4 08:12:13 INFO - docopt == 0.6.1 08:12:13 INFO - functools32 == 3.2.3-2 08:12:13 INFO - jsonschema == 2.5.1 08:12:13 INFO - manifestparser == 1.1 08:12:13 INFO - mozInstall == 1.12 08:12:13 INFO - mozcrash == 0.17 08:12:13 INFO - mozdebug == 0.1 08:12:13 INFO - mozdevice == 0.48 08:12:13 INFO - mozfile == 1.2 08:12:13 INFO - mozhttpd == 0.7 08:12:13 INFO - mozinfo == 0.9 08:12:13 INFO - mozleak == 0.1 08:12:13 INFO - mozlog == 3.4 08:12:13 INFO - moznetwork == 0.27 08:12:13 INFO - mozprocess == 0.23 08:12:13 INFO - mozprofile == 0.28 08:12:13 INFO - mozrunner == 6.12 08:12:13 INFO - mozscreenshot == 0.1 08:12:13 INFO - mozsystemmonitor == 0.3 08:12:13 INFO - moztest == 0.7 08:12:13 INFO - mozversion == 1.4 08:12:13 INFO - psutil == 3.1.1 08:12:13 INFO - requests == 1.2.3 08:12:13 INFO - wsgiref == 0.1.2 08:12:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:13 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 0x7ffb6fcd3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c9790>, '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 0x20ca690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cab20>, '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': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:12:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:12:13 INFO - Using env: {'DISPLAY': ':0', 08:12:13 INFO - 'HOME': '/home/cltbld', 08:12:13 INFO - 'LANG': 'en_US.UTF-8', 08:12:13 INFO - 'LANGUAGE': 'en_US:en', 08:12:13 INFO - 'LOGNAME': 'cltbld', 08:12:13 INFO - 'MAIL': '/var/mail/cltbld', 08:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:13 INFO - 'MOZ_NO_REMOTE': '1', 08:12:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:13 INFO - 'NO_EM_RESTART': '1', 08:12:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:13 INFO - 'PWD': '/builds/slave/test', 08:12:13 INFO - 'SHELL': '/bin/bash', 08:12:13 INFO - 'SHLVL': '1', 08:12:13 INFO - 'TERM': 'linux', 08:12:13 INFO - 'TMOUT': '86400', 08:12:13 INFO - 'USER': 'cltbld', 08:12:13 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 08:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:13 INFO - '_': '/tools/buildbot/bin/python'} 08:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:12:13 INFO - Cleaning up... 08:12:13 INFO - Return code: 0 08:12:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:12:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:12:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:12:13 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 0x7ffb6fcd3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203a7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c9790>, '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 0x20ca690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cab20>, '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': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:12:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:12:13 INFO - Using env: {'DISPLAY': ':0', 08:12:13 INFO - 'HOME': '/home/cltbld', 08:12:13 INFO - 'LANG': 'en_US.UTF-8', 08:12:13 INFO - 'LANGUAGE': 'en_US:en', 08:12:13 INFO - 'LOGNAME': 'cltbld', 08:12:13 INFO - 'MAIL': '/var/mail/cltbld', 08:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:13 INFO - 'MOZ_NO_REMOTE': '1', 08:12:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:13 INFO - 'NO_EM_RESTART': '1', 08:12:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:13 INFO - 'PWD': '/builds/slave/test', 08:12:13 INFO - 'SHELL': '/bin/bash', 08:12:13 INFO - 'SHLVL': '1', 08:12:13 INFO - 'TERM': 'linux', 08:12:13 INFO - 'TMOUT': '86400', 08:12:13 INFO - 'USER': 'cltbld', 08:12:13 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 08:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:13 INFO - '_': '/tools/buildbot/bin/python'} 08:12:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:12:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:12:14 INFO - Cleaning up... 08:12:14 INFO - Return code: 0 08:12:14 INFO - Running post-action listener: _resource_record_post_action 08:12:14 INFO - Running post-action listener: _start_resource_monitoring 08:12:14 INFO - Starting resource monitoring. 08:12:14 INFO - [mozharness: 2016-09-17 15:12:14.111729Z] Finished create-virtualenv step (success) 08:12:14 INFO - [mozharness: 2016-09-17 15:12:14.112202Z] Running install step. 08:12:14 INFO - Running pre-action listener: _resource_record_pre_action 08:12:14 INFO - Running main action method: install 08:12:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:12:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:12:14 INFO - Reading from file tmpfile_stdout 08:12:14 INFO - Detecting whether we're running mozinstall >=1.0... 08:12:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:12:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:12:14 INFO - Reading from file tmpfile_stdout 08:12:14 INFO - Output received: 08:12:14 INFO - Usage: mozinstall [options] installer 08:12:14 INFO - Options: 08:12:14 INFO - -h, --help show this help message and exit 08:12:14 INFO - -d DEST, --destination=DEST 08:12:14 INFO - Directory to install application into. [default: 08:12:14 INFO - "/builds/slave/test"] 08:12:14 INFO - --app=APP Application being installed. [default: firefox] 08:12:14 INFO - mkdir: /builds/slave/test/build/application 08:12:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:12:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:12:31 INFO - Reading from file tmpfile_stdout 08:12:31 INFO - Output received: 08:12:31 INFO - /builds/slave/test/build/application/firefox/firefox 08:12:31 INFO - Running post-action listener: _resource_record_post_action 08:12:31 INFO - [mozharness: 2016-09-17 15:12:31.336635Z] Finished install step (success) 08:12:31 INFO - [mozharness: 2016-09-17 15:12:31.336883Z] Running run-tests step. 08:12:31 INFO - Running pre-action listener: _resource_record_pre_action 08:12:31 INFO - Running main action method: run_tests 08:12:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:12:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:12:31 INFO - Python 2.7.3 08:12:31 INFO - Return code: 0 08:12:31 INFO - grabbing minidump binary from tooltool 08:12:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:31 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 0x20c9790>, '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 0x20ca690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:12:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:12:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:12:31 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:12:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:12:31 INFO - Return code: 0 08:12:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:12:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:12:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:12:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:12:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:12:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:12:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:12:31 INFO - Using env: {'DISPLAY': ':0', 08:12:31 INFO - 'HOME': '/home/cltbld', 08:12:31 INFO - 'LANG': 'en_US.UTF-8', 08:12:31 INFO - 'LANGUAGE': 'en_US:en', 08:12:31 INFO - 'LOGNAME': 'cltbld', 08:12:31 INFO - 'MAIL': '/var/mail/cltbld', 08:12:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:12:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:12:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:31 INFO - 'MOZ_NO_REMOTE': '1', 08:12:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:12:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:12:31 INFO - 'NO_EM_RESTART': '1', 08:12:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:12:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:31 INFO - 'PWD': '/builds/slave/test', 08:12:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:12:31 INFO - 'SHELL': '/bin/bash', 08:12:31 INFO - 'SHLVL': '1', 08:12:31 INFO - 'TERM': 'linux', 08:12:31 INFO - 'TMOUT': '86400', 08:12:31 INFO - 'USER': 'cltbld', 08:12:31 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354', 08:12:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:31 INFO - '_': '/tools/buildbot/bin/python'} 08:12:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:12:31 INFO - mozversion application_buildid: 20160916163001 08:12:31 INFO - mozversion application_changeset: b401cb17167b34c362eb819259effbb3c0979f59 08:12:31 INFO - mozversion application_display_name: Nightly 08:12:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:12:31 INFO - mozversion application_name: Firefox 08:12:31 INFO - mozversion application_remotingname: firefox 08:12:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:12:31 INFO - mozversion application_vendor: Mozilla 08:12:31 INFO - mozversion application_version: 51.0a1 08:12:31 INFO - mozversion platform_buildid: 20160916163001 08:12:31 INFO - mozversion platform_changeset: b401cb17167b34c362eb819259effbb3c0979f59 08:12:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:12:31 INFO - mozversion platform_version: 51.0a1 08:12:31 INFO - using testdate: 1474125151 08:12:31 INFO - actual date: 1474125151 08:12:31 INFO - starting webserver on 'localhost:45881' 08:12:31 INFO - SUITE-START | Running 2 tests 08:12:31 INFO - TEST-START | dromaeo_css 08:12:31 INFO - operating with platform_type : linux_ 08:12:31 INFO - Initialising browser for dromaeo_css test... 08:12:31 INFO - TEST-INFO | started process 6302 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzjQI0E/profile http://localhost:45881/getInfo.html) 08:12:35 INFO - PROCESS | 6302 | __metrics Screen width/height:1600/1200 08:12:35 INFO - PROCESS | 6302 | colorDepth:24 08:12:35 INFO - PROCESS | 6302 | Browser inner width/height: 1024/697 08:12:35 INFO - PROCESS | 6302 | __metrics 08:12:36 INFO - PROCESS | 6302 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:12:36 INFO - PROCESS | 6302 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:12:37 INFO - PROCESS | 6302 | console.error: PushService: 08:12:37 INFO - PROCESS | 6302 | stateChangeProcessEnqueue: Error transitioning state 08:12:37 INFO - PROCESS | 6302 | UnknownError 08:12:37 INFO - TEST-INFO | 6302: exit 0 08:12:37 INFO - Browser initialized. 08:12:37 INFO - Running cycle 1/1 for dromaeo_css test... 08:12:37 INFO - TEST-INFO | started process 6383 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzjQI0E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:12:49 INFO - PROCESS | 6383 | RSS: Main: 173113344 08:12:49 INFO - PROCESS | 6383 | 08:12:58 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:12:59 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 2.01675 seconds. 08:13:24 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 08:13:29 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 08:13:41 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 08:13:41 INFO - PROCESS | 6383 | 1474125221180 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:13:48 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:13:50 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:13:52 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:13:54 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.16673 seconds. 08:13:57 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:13:59 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 08:14:03 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 08:14:07 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:14:11 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 08:14:16 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 08:14:23 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:14:26 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:14:28 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 08:14:33 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.0501 seconds. 08:14:41 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:14:43 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:14:54 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:14:56 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:14:58 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:15:05 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:15:09 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:15:20 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:15:28 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:15:42 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:15:44 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:15:46 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:15:50 INFO - PROCESS | 6383 | Cycle 1(1): loaded http://localhost:45881/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45881/tests/dromaeo/cssquery-ext.html) 08:15:51 INFO - PROCESS | 6383 | RSS: Main: 320077824 08:15:51 INFO - PROCESS | 6383 | 08:15:55 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.08352 seconds. 08:16:00 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:16:08 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:16:18 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:16:41 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:16:46 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 08:16:57 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:17:03 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:17:09 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:17:21 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:17:55 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:18:01 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:18:04 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:18:15 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:18:19 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:18:36 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:18:53 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:18:54 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:18:54 INFO - PROCESS | 6383 | Cycle 1(1): loaded http://localhost:45881/tests/dromaeo/cssquery-ext.html (next: http://localhost:45881/tests/dromaeo/cssquery-jquery.html) 08:18:54 INFO - PROCESS | 6383 | RSS: Main: 274903040 08:18:54 INFO - PROCESS | 6383 | 08:18:58 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:19:07 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:19:09 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:19:34 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:19:35 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:19:37 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 08:20:17 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:20:32 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:20:38 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:20:43 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 08:20:44 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:20:48 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:21:12 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:21:20 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:21:22 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:21:26 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01663 seconds. 08:21:34 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:21:36 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:21:43 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:21:52 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:21:54 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:21:56 INFO - PROCESS | 6383 | Cycle 1(1): loaded http://localhost:45881/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45881/tests/dromaeo/cssquery-mootools.html) 08:21:57 INFO - PROCESS | 6383 | RSS: Main: 215412736 08:21:57 INFO - PROCESS | 6383 | 08:22:00 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:22:05 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:22:10 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:22:22 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:22:40 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:22:51 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 08:22:53 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:22:54 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:22:56 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:23:04 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:23:12 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:23:14 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:23:28 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:23:30 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:23:34 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:23:48 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:23:56 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:24:00 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 08:24:15 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:24:23 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:24:25 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:24:35 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:24:41 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:24:47 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:24:57 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:24:59 INFO - PROCESS | 6383 | Cycle 1(1): loaded http://localhost:45881/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45881/tests/dromaeo/cssquery-prototype.html) 08:25:00 INFO - PROCESS | 6383 | RSS: Main: 279326720 08:25:00 INFO - PROCESS | 6383 | 08:25:03 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 08:25:18 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:25:22 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:25:36 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:25:47 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:26:02 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01678 seconds. 08:26:08 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:26:11 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 08:26:27 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:26:44 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:26:47 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:26:52 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:26:54 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 08:26:56 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:26:58 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:27:14 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:27:18 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:27:29 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:27:39 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:27:50 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 08:27:55 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 08:28:02 INFO - PROCESS | 6383 | Cycle 1(1): loaded http://localhost:45881/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45881/tests/dromaeo/cssquery-yui.html) 08:28:03 INFO - PROCESS | 6383 | RSS: Main: 216641536 08:28:03 INFO - PROCESS | 6383 | 08:28:06 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:28:09 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:28:12 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:28:21 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:28:23 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:28:25 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:28:30 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:28:48 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:29:04 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:29:10 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:29:12 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:29:15 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 08:29:18 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:29:31 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:29:34 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 08:29:49 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:29:51 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:29:55 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.01661 seconds. 08:29:59 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:30:13 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:30:35 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:30:37 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:30:39 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 08:30:41 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:30:45 INFO - PROCESS | 6383 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:31:06 INFO - PROCESS | 6383 | Cycle 1(1): loaded http://localhost:45881/tests/dromaeo/cssquery-yui.html (next: http://localhost:45881/tests/dromaeo/cssquery-dojo.html) 08:31:06 INFO - PROCESS | 6383 | RSS: Main: 307417088 08:31:06 INFO - PROCESS | 6383 | 08:31:06 INFO - PROCESS | 6383 | __start_tp_report 08:31:06 INFO - PROCESS | 6383 | _x_x_mozilla_page_load 08:31:06 INFO - PROCESS | 6383 | _x_x_mozilla_page_load_details 08:31:06 INFO - PROCESS | 6383 | |i|pagename|runs| 08:31:06 INFO - PROCESS | 6383 | |0;dojo.html;2957;3025.0471101603007;3025.8184508929467;3064.2952121012154;3080.260737423018;4989.451160372356;5035.546800787928;5150.8454746357575;5186.688798672078;5228.137357336035;5117.130087885059;5186.273921650963;5192.896142077157;5232.241325007872;5255.500727430888;4618.676692631517;4658.813647454098;4663.090697314008;4669.533046695337;4722.527747225267;4780.80876764929;4818.710877347353;4829.7343646099625;4834.782434790425;4861.73260470674;4616.561426664462;4662.393888794435;4674.158651442739;4697.459792123881;4753.90492190156;4764.356811830427;4766.261229509397;4770.8330208442885;4810.158222311082;4839.2983017462275;4710.599599034086;4749.810007599692;4757.167495688241;4764.928526072112;4808.302796094582;5100.311457953166;5144;5253.106971814801;5256.605754568384;5323.866903327409;5509.449055094498;5573.108302671553;5576.693281869496;5580.91628625571;5643.238162848004;5585.832425027255;5586.217929489874;5602.327720673505;5602.803901863456;5605.103183490622;5064.088464076428;5237.554807841338;5239.895202095937;5244.711540865251;5304.1778524329175;5628.577856660685;5635.0702134147405;5635.549156067505;5640.576956728261;5713.371529131792;5441;5441.891162176735;5443.727813609304;5447.536959358499;5450.073487507133;5520.585956053312;5583.888322233533;5584.078627026576;5586.050371436865;5598.412166722517;5412.945870541323;5428.6471379361;5476.835694929158;5485.6434331768305;5490.121580547093;5508.724563771875;5514.61397702157;5518.19986102013;5519.668819870821;5569.359523654751;4292.163028209469;4292.420523229386;4294.26997410441;4340;4343.131373725268;3790.33669107905;3795.9620403795607;3816.542014958223;3846.4614953906475;3855.749376290532;5221.373435187802;5226.97386513065;5229.555487783505;5259.47405259471;5297.046531624267;5482.725863706799;5491.670499770026;5493.792618489938;5494.478024587658;5514.007478653881;5067.543921047123;5113.769880355393;5224.660397074178;5236.240745092016;5262.2632831403635;4833.4441539223435;4852.1508735971065;4860.465348811628;4913.533214344631;4916.360873086547;5788.884222315446;5794.652320860761;5795.710214489343;5798.10129429939;5805.535557155417;5465.316835395575;5518.834434966958;5546.315027097096;5625.212470254086;5672.744726487235;5731.168980497721;5759.798407055733;5778.739956701875;5800.8259752207505;5857.58996870215;5477.233187353853;5512.6692398456225;5515.503604675438;5516.779328826802;5569.805056822992;5605.579581531483;5608.859778505407;5611.943880561143;5616.269884914935;5617.297837770278;5216.347956505437;5223.503767142115;5370.489803468664;5421.0513160196015;5529.778808847601;5432.103702888979;5433.809816656398;5439.5104440600535;5490.3411590609385;5499.752511137089;5368.677879327252;5370.221317908803;5372.597055220952;5377.435369286325;5439.020976224159;5264.447233040629;5279.340082489689;5431.076716958048;5435;5455.290812194548;5376;5379.865503362447;5384.407715151252;5386.730663466733;5457.590680698884 08:31:06 INFO - PROCESS | 6383 | |1;ext.html;546.1100532578122;587.1339773833598;593.0333556303225;606.4753987800551;607.6414915200031;31813.68186318139;42410.57589424105;43526.12947741043;43611.56388436119;46186.307205391946;43719;44016.77991610045;44238.672839814855;44251.77874110625;44311.77844110783;9004.414713043647;9154.49650269235;9159.35884488086;9165.679201227958;9167;8913.24237439815;8949.284057275436;8950;9032.954835225848;9033.819323613523;8941.50821704806;8978.23684986777;8986.775330616721;8990.505522196276;9062.77343066422;9029.683961061364;9068.274538036942;9078;9080.318976076787;9087.227585655228;8834.911650883474;8948.64205431782;8949.105089490999;8952.731418057469;8955.641774329019;41878;41884.79057604723;42266.788666056476;42267.36598951018;42335.36496952517;3538.5222994895616;3642.9271414571826;4097.631213190827;4118.135191609736;4170.645495132917;31839.52240716391;31928.521072183703;32291.838540807144;32295.192620184447;32319.838400807846;4269.167512335096;4352.499462561815;4407.823687052514;4587.632989360844;4603.309503574491;334989;335243;335735;336242;336664;54119;54329;54630.726846366306;54752;54835;57300;57400;57436.13845792317;57498.425015750145;57541.42458575361;13827.723445531234;13913.930430347784;13946.86053139476;13950.09324393911;13950.372233249529;11053.502592383362;11073.723156921142;11083.72290692723;11088.722781930517;11097.611583594535;12924.547640832714;15447.918645694694;15459.92270038665;15479.22603869802;15701;6031.276246850318;6094.634321940609;6208.627482350983;6298.7795427160745;6535;721.073420654467;723.462269502135;838.5974732128612;913.9771505712278;978.2027647467291;29557.408851822842;30895.2276193097;30955.226119347197;31307;31512;1036.3781730961364;1047.9161667066617;1052.079430498314;1071.807074726541;1116.0067539889505;15991.840081599035;16289.266982985793;16344.203619009697;16443.753343699853;16508.587285317964;449243;451203;453361;456253;457245;55594.72202638961;55877.161842572394;55902.440975590536;55992;56009.439905600426;1148.9138314626437;1214.423149004216;1276.5085442104858;1290.0969321475004;1311.567182829664;28797.85601072065;31386.372272554876;32399;33605.663943360254;34009.31981360408;52124;52686;52759.20861187008;52830;52860.471395287095;405164;407743;408435;409419;410341;26681.733182667926;27205.86397068002;27293.863530682218;39456.21087578175;39770.80114599524;46309;47273;47371.28943065788;47770;48293.51706483031;37910.810445947594;39072.2185556293;40248.1950360997;40880.59119408887;41782.37326440045;39122;39291;40043.3993490092;40179;41281.380779288935;39513;39608.20783584254;39806.40290395589;39933.40099898562;39982;32722.50916236211;34640;34791.82604086964;35430.822845886636;35996.28007439889;26520.602190966765;26955.730442695323;26971.460570788866;27472.538357154186;27811.165665030083 08:31:06 INFO - PROCESS | 6383 | |2;jquery.html;358.7022771099987;396.79366729300784;408.8834682115598;410.4131092537671;424.4482173174874;13114.213147211174;14956.476523321673;18667.25330986762;19001.809981900176;19458.1243844027;18576.628467430644;18581.721274180865;18849.81150188498;19141.712874306897;19241.326553570627;5555;5617.41017193195;5849.85375365615;5851.327097383792;5853.326867410238;5651.378348381675;5696.800611978581;5701.942980570204;5720.570957178207;5798;5624.606277560572;5678.176664383683;5689.459501347386;5702.315722113352;5729.341125770548;5851.122331650266;6028.336882942873;6038.3659715729655;6052.606580572271;6058.727357268912;5133.743312834344;5365.946340536584;5408.783648654088;5874.206982057411;5903.675297858616;14257.572272831832;14408.423663053465;14434.278286085653;14486.34811433488;14537.92731036338;1988.7315212446279;2195.7255343082115;2407.807375409963;2660.2285337252174;2893.1772983020774;10265.384076955332;10282.640107596273;10422.791544169075;10633.202509811717;12078.912897839236;1796.9550761230944;1814.2380200315768;1826.6346730653922;1863.813618638139;1937.7384053152784;75059.49881002404;83564.58217708873;84238.57880710618;84558;84809.57595212046;11248.26886252391;11253.212275140662;11457.14071444645;12111.697207569881;12227.755444891052;15741.527754167393;15762.527124186292;15764.211789410483;15799.36802527886;15897.443589474313;7466.887996680054;7480.551166930001;7641.426892983049;7839.216078392229;8020.398470114765;6900.413464855443;6988.301169883072;7125.287471252833;7182.102237220348;7213.49505534618;4728.935989402357;4833.10587541306;4858.368412106403;4996.4503904570465;4999.775010124553;1982.5334030006832;1986.2352994097378;2037.7758446570865;2047.4983629010987;2050.5283784729595;796.3310818912141;876.9429987050821;901.2069378946485;955.0974329258784;977.4184360305607;17565.385211517536;18102.823316484388;19058.523536911413;19071.90464047671;19395.8060419394;907.3240435875183;935.9672411465566;957.4303289542711;985.3299756165733;985.8521221816642;9590.520473976412;10236.948815255875;10596.258261921592;10704.250702450754;10748.946255268673;398559;399774;416840;425147;433611;88683;91838;92181.53909230411;92316.53841730749;94607.52696236475;979.6522234606612;981.4356744871585;985.408754747146;988.8318985772436;991.687618400216;16384.590385240463;16721.331146753993;16911;17832.286708531512;17964.191611377257;39463.210735785695;43836.34245486384;43874.34188487239;44505;45146.54853451424;48100.27849582189;55782;56552.717236413555;57009;57435.71282143563;8520.275776558814;8689.782755431164;9052.411593246681;11208.983927379179;12254.4485498151;13170.143940643828;13212.801807972695;13770.586882393543;14088;14378.137311761328;12229.143959923076;12304.446299916346;12383.69040773988;12668.683282918;12722.936385318015;12100.757984840078;12152.392380381123;12206.816897746363;12374.690632734253;12797.552085676956;12670.683232919251;12759.93620031894;12783.424745886643;12804;13067.215967042008;12651.683707907008;12688.492460301484;12761.93619031899;12848.550300739429;13227.404766785325;12570.245785252913;12711.427985740478;12777.8083328752;12791.936040319739;13040.478380865039 08:31:06 INFO - PROCESS | 6383 | |3;mootools.html;1359.2048651538848;1476.4463326252658;1509.08700236357;1646.6212771062656;1787.6692811829812;7819.061712594489;8945.597448114835;9190.264778817695;9191.126842949925;9518.85721714173;9268.119528644786;9345.43927364357;9351.90648093519;9358.859617105749;9359.064093590638;2378.3340664613934;2404.026369320428;2405;2406.0736616402723;2406.9037238510437;2307.400075980249;2359.327591636384;2360.4688944987324;2361.114582031738;2370.8933098010543;2287.7369102553253;2299.0803678528555;2317.663938728892;2387.797037251827;2388.5819981503255;2244.1023590563905;2304.043821813945;2306.1928325086255;2321.059970711856;2344.5662552427857;2299.839011269195;2304.1244327155573;2309.7921187093243;2310.572544079351;2312.063614236237;9185.448873067637;9247.907520924771;9265.629374825;9305.860412093823;9314.301427392973;5113.565346945514;5331.866703332409;5337.546308563777;5341.626086173969;5344.251804747339;3500.7199424046016;3501.57981042274;3565.6612621800887;3573.4639804029343;3573.8213089345427;1198.0774803401418;1203.6750077479119;1220.6826225181428;1237.7215126596534;1245.6014075495846;12907.419166137546;12957.09300348976;12975.545855894998;12991.675208119777;12997.805032924514;4400.889977750582;4646.535346465326;4727.763611819395;4762.833300834454;4770.332153498513;10086.092251697382;10112.241581881388;10124.190064794799;10127.696169114939;10137.898621013843;5437.891242175135;5443.42844001382;5449.455054494519;5449.727513624303;5453.072977593819;4349.217140914602;4476.746510976932;4838.056578967067;4951.331570238044;5077.238414237894;3949.1311911379453;3953.486046813953;4062.654674352654;4070.7964601769795;4098.692598055158;1218.3968935377045;1228.3244215681339;1240.4418011894684;1246.5325502936398;1267.9112462127748;796.9083555391088;925.2829057480507;950.5817440326234;956.1872408452898;1005.4017859373661;5999.670018149044;6153.815385538441;6189.071639254148;6254.718537665816;6431.871362572722;463.1616773639723;464.37077759635883;468.6883222656963;472.36702818223745;479.6618384039244;4279.743215407086;4322.675799315064;4325.070109926381;4373.2128216921255;4386.188555117314;11081.667549973514;11308.208425410307;11382.94308528452;11400.828987565194;11403.42982850854;10878.456077196108;11257.94371028156;11330.37682927447;11421.828672569918;11452.198346115858;8436.873446898302;8616.956915215384;8620;8621.870671939925;8628.22345988864;5815.9709201453725;5835.9708201458725;5847.970760146172;5882.852928676817;6132.35610260934;9911.603535858774;9936.900630993887;9955.253355998473;9955.303128780915;9963.850542241727;10461.843072354073;10499.94750026245;10534.789304214026;10567.89432105669;10593.947030264799;7285.927140728525;7763.883541746991;8036.1160272368825;8045.919540804517;8230.588470576567;8889.422187557788;9055.18503334703;9064.818703625759;9082.45505269686;9121.45271283725;7852.725154619561;8113.229243221885;8113.6754529818145;8243.711470098518;8332.291755200877;8079;8097.433179677366;8192;8199.42604017713;8366.33069354438;7404.370628496638;7424.183339832722;7425.70297188128;7432.479726419315;7523.210062943313;5395.109806881977;5438.063445739256;5439.673619582838;5484.972575137099;5491.588130890119;5359.62482626228;5411.323584551931;5462.098753705594;5522.696251706034;5582.218489411404 08:31:06 INFO - PROCESS | 6383 | |4;prototype.html;318.6128853443066;323.5308802236756;337.3859575572458;339.52805600215703;342.0678650676907;5115.565176959963;5203.115470370037;5226.42509323975;5228.764705588248;5260.763720525673;5202.869928251786;5206.635535512516;5225.6603320784125;5229.189475631274;5236.423993360737;4116.794160291988;4151.958480415203;4163.604457576525;4185.744276716988;4186.099988502471;4102.97948510257;4119.567445418218;4162.562930892258;4168.62482376586;4199.874003779892;4114.526829414625;4116.403121547389;4120.072983578686;4127.463429754128;4129.256733787917;4119.279126152911;4138.379243113542;4147.7647435000745;4150.107726838744;4166.604172603597;3726.2920045191327;3728.6644202021675;3731.9440208396895;3733.906652333686;3737.626237376268;4948.950510494921;5076;5102.311187989611;5129.948700513021;5151.201563757623;4745.406824146981;4768.47546769855;4773.260144677581;4773.76131193439;4793.328933949215;5294.285271488338;5467.45325467454;5468.578169675873;5480.095784195603;5494.642848214893;3985.621365970228;4013.518377794654;4020.135670830756;4021.5375231848125;4044.1305119399176;5291.86770330741;5463.890722185573;5605.915911261334;5660.896125255576;5669.149627555858;4036.616521430459;4101.89745256365;4113.457453454955;4122.732022418533;4126.958730412717;4179;4199.853005144866;4203.642690371292;4206.917575799998;4215.135897141079;4549.203889319356;4742.549457801503;4748.572628463455;4764.809407623657;4778.474367819537;5483.588730845203;5489.917651235235;5490.6431081979545;5500.669959802424;5514.297993982919;1439.3882599895003;1457.256799032501;1464.3630020940925;1468;1469.3461409672807;917.5432510736475;925.949047830717;930.8557173638097;946.6733976778;963.1668606655332;4622.422197225347;4643.744594047293;4690.601298889633;4705.905881882343;4743.715377077387;4889.677403233436;4901.436334821541;4901.975490122598;4939.678920870132;4943.233798761125;4679.672422930362;4680.461746899082;4715.24556071027;4722.48052714208;4975.726335051534;2947.484190266695;2973.687762784876;2981.120569432023;2985.1641540368732;2992.640883093999;5743.62666426681;5794.030029789892;5813.866296072225;5837.7956771513645;5867.970660146672;5536.252605898152;5755.532339253464;5757.992351338499;5761.279840019997;5769.826905192851;5722.113072473773;5728.656280623092;5729.7708091676695;5735.569832262597;5749.464079591105;5354.892902142014;5616.438356164351;5622.116590942081;5634.802781902614;5654.943450565606;5480.342358916955;5493.258410114583;5532.3637781654015;5562.360328562106;5564.19319198722;5570.637908535932;5649.491545760901;5651.321841379061;5781.248437703071;5799.695068609529;4081.91836163281;4094.61101195386;4108.383742438658;4112.568180341141;4121.464209652726;5607.607467477237;5649.548036157015;5688.77244910199;5717.628354157133;5724.885502290014;5243;5459.372172200247;5461.617686761889;5465.535429488538;5475.6440831345835;5389.326334208224;5451.863703407288;5482.150266708666;5526.447355264441;5605.5235304997905;5237.528622424;5388.919166212431;5476;5548.889022219452;5561.499465048165;5512.145617429305;5514.228008078952;5523;5531.253280807039;5534.446555344433;5409.701671598867;5421.484958928895;5495.664342654276;5505.614606977473;5508.476694713996 08:31:06 INFO - PROCESS | 6383 | |5;yui.html;700.8913618389149;719.5107327017629;792.9088154862193;805.8710606302993;816.412183228076;15506.302216400261;16367.263473143707;16571.751423728652;16699.16504174791;16733.07968061756;13897.096688715226;14187.290635468236;14324.283785810694;14341.569752907404;14901.92549037256;779.7387875061861;803.3011280186217;807.4953154278576;807.935365170785;808.4340961327065;675.5946432140726;690.7858563845199;793.1909452358591;815.3192084609358;821.930135938443;872.8254349130168;873.8121303919656;876.4697358098355;880.810625715471;881.1276835932445;872.3936863879607;874.3748220022683;875.4615911214589;875.92992560595;876.1457578860624;506.82261208576705;521.0230817217716;613.1477246627192;625.7747211004034;639.763287583597;15419.614509637237;15451.61370965735;15463.304151313218;15467.149306788122;15469.613259668486;14125.223112728796;14402;14614.196219207939;14678.853211467855;14903.254837258093;12927.935360323232;12943.352832358438;13299.268540230283;13597.932010339884;13805.240711760849;4344.413504176927;4456.2647163218035;4479;4606.884827879297;4632.309217046581;77473.22526774772;77518.22481775223;78640;78995;79207.20792792113;10513.947430262953;11463.656090317303;11494.655160345203;11591.188616796911;11827.053835693125;19625;21193.36419907405;21199;21241.893790530947;21255.149794008066;7819.3744500439825;7934.642941067666;8012.3590112790835;8024.034111642568;8198.221168988937;4335.761533115646;4447.199504089231;4460.843870464583;4567.040921406476;4711.481737008926;3453.3438646657555;3576.5171701819922;3895.8052097395016;4079.1841631673783;4080.387941808693;679.8912174052128;683.4088513435942;695.5652717051843;697.0485287582413;702.4310308649908;679.4971720926479;709.975150869717;726.6330503096009;743.4201322968094;745.537766584721;9297.860532092023;10090.596376144871;10204.581220493123;10331.48342582868;10540.472976351151;618.1870839845678;642.6529673976094;647.9319671434525;710.5026481462996;722.2230523636608;4804.12976123577;4870.26945958102;4898.174506798374;4928.950710492921;5144.819931302386;110209;118874;120103.39948300377;122298;122566.3871680636;86750.56624716836;101955;103420;104493;106505;73368;88095;91401;92266.61867050486;93005.5349723247;8812.518382261263;8993.505357205417;9017.549122543714;9028.819423611621;9102.180803727431;53805.46194538005;55890.72054639701;55996.72001639966;57066;57191.71404142953;61925;64271;73327.26672733418;80548;80628.59685701533;13329.933350333185;13478.123921945042;13543.119697219649;13565.253911034988;13744.86255137436;14792.778108328599;15037.323320450825;15178.544643660267;15595.84404155944;16023.599410014844;13744;14055.718885622435;14194.3612537434;14435.855641443452;14480.131192128507;13991.860081399056;14039.438422463396;15018.098914064754;15179.924100379427;15200.619984500476;13469.326533673082;13520.79718804199;14026.579202623938;14273.214973176575;14274.785878212042;10704.464776760975;10927.398993055458;10954.428976037843;11122;11458.541658333574;9802.460864652512;10020.198384129399;10080.344777589433;10842.945785271024;11061.059809916247 08:31:06 INFO - PROCESS | 6383 | __end_tp_report 08:31:06 INFO - PROCESS | 6383 | __start_cc_report 08:31:06 INFO - PROCESS | 6383 | _x_x_mozilla_cycle_collect,1148 08:31:06 INFO - PROCESS | 6383 | __end_cc_report 08:31:06 INFO - PROCESS | 6383 | __startTimestamp1474126266802__endTimestamp 08:31:06 INFO - PROCESS | 6383 | 08:31:06 INFO - PROCESS | 6383 | ------- Summary: start ------- 08:31:06 INFO - PROCESS | 6383 | Number of tests: 6 08:31:06 INFO - PROCESS | 6383 | 08:31:06 INFO - PROCESS | 6383 | [#0] dojo.html Cycles:165 Average:5166.82 Median:5372.60 stddev:575.69 (10.7%) stddev-sans-first:550.74 08:31:06 INFO - PROCESS | 6383 | Values: 2957.0 3025.0 3025.8 3064.3 3080.3 4989.5 5035.5 5150.8 5186.7 5228.1 5117.1 5186.3 5192.9 5232.2 5255.5 4618.7 4658.8 4663.1 4669.5 4722.5 4780.8 4818.7 4829.7 4834.8 4861.7 4616.6 4662.4 4674.2 4697.5 4753.9 4764.4 4766.3 4770.8 4810.2 4839.3 4710.6 4749.8 4757.2 4764.9 4808.3 5100.3 5144.0 5253.1 5256.6 5323.9 5509.4 5573.1 5576.7 5580.9 5643.2 5585.8 5586.2 5602.3 5602.8 5605.1 5064.1 5237.6 5239.9 5244.7 5304.2 5628.6 5635.1 5635.5 5640.6 5713.4 5441.0 5441.9 5443.7 5447.5 5450.1 5520.6 5583.9 5584.1 5586.1 5598.4 5412.9 5428.6 5476.8 5485.6 5490.1 5508.7 5514.6 5518.2 5519.7 5569.4 4292.2 4292.4 4294.3 4340.0 4343.1 3790.3 3796.0 3816.5 3846.5 3855.7 5221.4 5227.0 5229.6 5259.5 5297.0 5482.7 5491.7 5493.8 5494.5 5514.0 5067.5 5113.8 5224.7 5236.2 5262.3 4833.4 4852.2 4860.5 4913.5 4916.4 5788.9 5794.7 5795.7 5798.1 5805.5 5465.3 5518.8 5546.3 5625.2 5672.7 5731.2 5759.8 5778.7 5800.8 5857.6 5477.2 5512.7 5515.5 5516.8 5569.8 5605.6 5608.9 5611.9 5616.3 5617.3 5216.3 5223.5 5370.5 5421.1 5529.8 5432.1 5433.8 5439.5 5490.3 5499.8 5368.7 5370.2 5372.6 5377.4 5439.0 5264.4 5279.3 5431.1 5435.0 5455.3 5376.0 5379.9 5384.4 5386.7 5457.6 08:31:06 INFO - PROCESS | 6383 | 08:31:06 INFO - PROCESS | 6383 | [#1] ext.html Cycles:180 Average:56153.65 Median:41830.19 stddev:106163.95 (253.8%) stddev-sans-first:106379.67 08:31:06 INFO - PROCESS | 6383 | Values: 546.1 587.1 593.0 606.5 607.6 31813.7 42410.6 43526.1 43611.6 46186.3 43719.0 44016.8 44238.7 44251.8 44311.8 9004.4 9154.5 9159.4 9165.7 9167.0 8913.2 8949.3 8950.0 9033.0 9033.8 8941.5 8978.2 8986.8 8990.5 9062.8 9029.7 9068.3 9078.0 9080.3 9087.2 8834.9 8948.6 8949.1 8952.7 8955.6 41878.0 41884.8 42266.8 42267.4 42335.4 3538.5 3642.9 4097.6 4118.1 4170.6 31839.5 31928.5 32291.8 32295.2 32319.8 4269.2 4352.5 4407.8 4587.6 4603.3 334989.0 335243.0 335735.0 336242.0 336664.0 54119.0 54329.0 54630.7 54752.0 54835.0 57300.0 57400.0 57436.1 57498.4 57541.4 13827.7 13913.9 13946.9 13950.1 13950.4 11053.5 11073.7 11083.7 11088.7 11097.6 12924.5 15447.9 15459.9 15479.2 15701.0 6031.3 6094.6 6208.6 6298.8 6535.0 721.1 723.5 838.6 914.0 978.2 29557.4 30895.2 30955.2 31307.0 31512.0 1036.4 1047.9 1052.1 1071.8 1116.0 15991.8 16289.3 16344.2 16443.8 16508.6 449243.0 451203.0 453361.0 456253.0 457245.0 55594.7 55877.2 55902.4 55992.0 56009.4 1148.9 1214.4 1276.5 1290.1 1311.6 28797.9 31386.4 32399.0 33605.7 34009.3 52124.0 52686.0 52759.2 52830.0 52860.5 405164.0 407743.0 408435.0 409419.0 410341.0 26681.7 27205.9 27293.9 39456.2 39770.8 46309.0 47273.0 47371.3 47770.0 48293.5 37910.8 39072.2 40248.2 40880.6 41782.4 39122.0 39291.0 40043.4 40179.0 41281.4 39513.0 39608.2 39806.4 39933.4 39982.0 32722.5 34640.0 34791.8 35430.8 35996.3 26520.6 26955.7 26971.5 27472.5 27811.2 08:31:06 INFO - PROCESS | 6383 | 08:31:06 INFO - PROCESS | 6383 | [#2] jquery.html Cycles:180 Average:26860.47 Median:1625.94 stddev:68903.28 (4237.8%) stddev-sans-first:69067.84 08:31:06 INFO - PROCESS | 6383 | Values: 358.7 396.8 408.9 410.4 424.4 13114.2 14956.5 18667.3 19001.8 19458.1 18576.6 18581.7 18849.8 19141.7 19241.3 5555.0 5617.4 5849.9 5851.3 5853.3 5651.4 5696.8 5701.9 5720.6 5798.0 5624.6 5678.2 5689.5 5702.3 5729.3 5851.1 6028.3 6038.4 6052.6 6058.7 5133.7 5365.9 5408.8 5874.2 5903.7 14257.6 14408.4 14434.3 14486.3 14537.9 1988.7 2195.7 2407.8 2660.2 2893.2 10265.4 10282.6 10422.8 10633.2 12078.9 1797.0 1814.2 1826.6 1863.8 1937.7 75059.5 83564.6 84238.6 84558.0 84809.6 11248.3 11253.2 11457.1 12111.7 12227.8 15741.5 15762.5 15764.2 15799.4 15897.4 7466.9 7480.6 7641.4 7839.2 8020.4 6900.4 6988.3 7125.3 7182.1 7213.5 4728.9 4833.1 4858.4 4996.5 4999.8 1982.5 1986.2 2037.8 2047.5 2050.5 796.3 876.9 901.2 955.1 977.4 17565.4 18102.8 19058.5 19071.9 19395.8 907.3 936.0 957.4 985.3 985.9 9590.5 10236.9 10596.3 10704.3 10748.9 398559.0 399774.0 416840.0 425147.0 433611.0 88683.0 91838.0 92181.5 92316.5 94607.5 979.7 981.4 985.4 988.8 991.7 16384.6 16721.3 16911.0 17832.3 17964.2 39463.2 43836.3 43874.3 44505.0 45146.5 48100.3 55782.0 56552.7 57009.0 57435.7 8520.3 8689.8 9052.4 11209.0 12254.4 13170.1 13212.8 13770.6 14088.0 14378.1 12229.1 12304.4 12383.7 12668.7 12722.9 12100.8 12152.4 12206.8 12374.7 12797.6 12670.7 12759.9 12783.4 12804.0 13067.2 12651.7 12688.5 12761.9 12848.6 13227.4 12570.2 12711.4 12777.8 12791.9 13040.5 08:31:06 INFO - PROCESS | 6383 | 08:31:06 INFO - PROCESS | 6383 | [#3] mootools.html Cycles:180 Average:5975.67 Median:4766.58 stddev:3448.02 (72.3%) stddev-sans-first:3440.23 08:31:06 INFO - PROCESS | 6383 | Values: 1359.2 1476.4 1509.1 1646.6 1787.7 7819.1 8945.6 9190.3 9191.1 9518.9 9268.1 9345.4 9351.9 9358.9 9359.1 2378.3 2404.0 2405.0 2406.1 2406.9 2307.4 2359.3 2360.5 2361.1 2370.9 2287.7 2299.1 2317.7 2387.8 2388.6 2244.1 2304.0 2306.2 2321.1 2344.6 2299.8 2304.1 2309.8 2310.6 2312.1 9185.4 9247.9 9265.6 9305.9 9314.3 5113.6 5331.9 5337.5 5341.6 5344.3 3500.7 3501.6 3565.7 3573.5 3573.8 1198.1 1203.7 1220.7 1237.7 1245.6 12907.4 12957.1 12975.5 12991.7 12997.8 4400.9 4646.5 4727.8 4762.8 4770.3 10086.1 10112.2 10124.2 10127.7 10137.9 5437.9 5443.4 5449.5 5449.7 5453.1 4349.2 4476.7 4838.1 4951.3 5077.2 3949.1 3953.5 4062.7 4070.8 4098.7 1218.4 1228.3 1240.4 1246.5 1267.9 796.9 925.3 950.6 956.2 1005.4 5999.7 6153.8 6189.1 6254.7 6431.9 463.2 464.4 468.7 472.4 479.7 4279.7 4322.7 4325.1 4373.2 4386.2 11081.7 11308.2 11382.9 11400.8 11403.4 10878.5 11257.9 11330.4 11421.8 11452.2 8436.9 8617.0 8620.0 8621.9 8628.2 5816.0 5836.0 5848.0 5882.9 6132.4 9911.6 9936.9 9955.3 9955.3 9963.9 10461.8 10499.9 10534.8 10567.9 10593.9 7285.9 7763.9 8036.1 8045.9 8230.6 8889.4 9055.2 9064.8 9082.5 9121.5 7852.7 8113.2 8113.7 8243.7 8332.3 8079.0 8097.4 8192.0 8199.4 8366.3 7404.4 7424.2 7425.7 7432.5 7523.2 5395.1 5438.1 5439.7 5485.0 5491.6 5359.6 5411.3 5462.1 5522.7 5582.2 08:31:06 INFO - PROCESS | 6383 | 08:31:06 INFO - PROCESS | 6383 | [#4] prototype.html Cycles:180 Average:4592.68 Median:5177.04 stddev:1319.44 (25.5%) stddev-sans-first:1283.55 08:31:06 INFO - PROCESS | 6383 | Values: 318.6 323.5 337.4 339.5 342.1 5115.6 5203.1 5226.4 5228.8 5260.8 5202.9 5206.6 5225.7 5229.2 5236.4 4116.8 4152.0 4163.6 4185.7 4186.1 4103.0 4119.6 4162.6 4168.6 4199.9 4114.5 4116.4 4120.1 4127.5 4129.3 4119.3 4138.4 4147.8 4150.1 4166.6 3726.3 3728.7 3731.9 3733.9 3737.6 4949.0 5076.0 5102.3 5129.9 5151.2 4745.4 4768.5 4773.3 4773.8 4793.3 5294.3 5467.5 5468.6 5480.1 5494.6 3985.6 4013.5 4020.1 4021.5 4044.1 5291.9 5463.9 5605.9 5660.9 5669.1 4036.6 4101.9 4113.5 4122.7 4127.0 4179.0 4199.9 4203.6 4206.9 4215.1 4549.2 4742.5 4748.6 4764.8 4778.5 5483.6 5489.9 5490.6 5500.7 5514.3 1439.4 1457.3 1464.4 1468.0 1469.3 917.5 925.9 930.9 946.7 963.2 4622.4 4643.7 4690.6 4705.9 4743.7 4889.7 4901.4 4902.0 4939.7 4943.2 4679.7 4680.5 4715.2 4722.5 4975.7 2947.5 2973.7 2981.1 2985.2 2992.6 5743.6 5794.0 5813.9 5837.8 5868.0 5536.3 5755.5 5758.0 5761.3 5769.8 5722.1 5728.7 5729.8 5735.6 5749.5 5354.9 5616.4 5622.1 5634.8 5654.9 5480.3 5493.3 5532.4 5562.4 5564.2 5570.6 5649.5 5651.3 5781.2 5799.7 4081.9 4094.6 4108.4 4112.6 4121.5 5607.6 5649.5 5688.8 5717.6 5724.9 5243.0 5459.4 5461.6 5465.5 5475.6 5389.3 5451.9 5482.2 5526.4 5605.5 5237.5 5388.9 5476.0 5548.9 5561.5 5512.1 5514.2 5523.0 5531.3 5534.4 5409.7 5421.5 5495.7 5505.6 5508.5 08:31:06 INFO - PROCESS | 6383 | 08:31:06 INFO - PROCESS | 6383 | [#5] yui.html Cycles:180 Average:21235.32 Median:4458.55 stddev:30641.89 (687.3%) stddev-sans-first:30689.06 08:31:06 INFO - PROCESS | 6383 | Values: 700.9 719.5 792.9 805.9 816.4 15506.3 16367.3 16571.8 16699.2 16733.1 13897.1 14187.3 14324.3 14341.6 14901.9 779.7 803.3 807.5 807.9 808.4 675.6 690.8 793.2 815.3 821.9 872.8 873.8 876.5 880.8 881.1 872.4 874.4 875.5 875.9 876.1 506.8 521.0 613.1 625.8 639.8 15419.6 15451.6 15463.3 15467.1 15469.6 14125.2 14402.0 14614.2 14678.9 14903.3 12927.9 12943.4 13299.3 13597.9 13805.2 4344.4 4456.3 4479.0 4606.9 4632.3 77473.2 77518.2 78640.0 78995.0 79207.2 10513.9 11463.7 11494.7 11591.2 11827.1 19625.0 21193.4 21199.0 21241.9 21255.1 7819.4 7934.6 8012.4 8024.0 8198.2 4335.8 4447.2 4460.8 4567.0 4711.5 3453.3 3576.5 3895.8 4079.2 4080.4 679.9 683.4 695.6 697.0 702.4 679.5 710.0 726.6 743.4 745.5 9297.9 10090.6 10204.6 10331.5 10540.5 618.2 642.7 647.9 710.5 722.2 4804.1 4870.3 4898.2 4929.0 5144.8 110209.0 118874.0 120103.4 122298.0 122566.4 86750.6 101955.0 103420.0 104493.0 106505.0 73368.0 88095.0 91401.0 92266.6 93005.5 8812.5 8993.5 9017.5 9028.8 9102.2 53805.5 55890.7 55996.7 57066.0 57191.7 61925.0 64271.0 73327.3 80548.0 80628.6 13329.9 13478.1 13543.1 13565.3 13744.9 14792.8 15037.3 15178.5 15595.8 16023.6 13744.0 14055.7 14194.4 14435.9 14480.1 13991.9 14039.4 15018.1 15179.9 15200.6 13469.3 13520.8 14026.6 14273.2 14274.8 10704.5 10927.4 10954.4 11122.0 11458.5 9802.5 10020.2 10080.3 10842.9 11061.1 08:31:06 INFO - PROCESS | 6383 | -------- Summary: end -------- 08:31:06 INFO - PROCESS | 6383 | 08:31:07 INFO - TEST-INFO | 6383: exit 0 08:31:07 INFO - TEST-OK | dromaeo_css | took 1116157ms 08:31:07 INFO - TEST-START | kraken 08:31:07 INFO - operating with platform_type : linux_ 08:31:07 INFO - Initialising browser for kraken test... 08:31:07 INFO - TEST-INFO | started process 6692 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjUSwq_/profile http://localhost:45881/getInfo.html) 08:31:12 INFO - PROCESS | 6692 | __metrics Screen width/height:1600/1200 08:31:12 INFO - PROCESS | 6692 | colorDepth:24 08:31:12 INFO - PROCESS | 6692 | Browser inner width/height: 1024/697 08:31:12 INFO - PROCESS | 6692 | __metrics 08:31:12 INFO - PROCESS | 6692 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:31:12 INFO - PROCESS | 6692 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:31:13 INFO - PROCESS | 6692 | console.error: PushService: 08:31:13 INFO - PROCESS | 6692 | stateChangeProcessEnqueue: Error transitioning state 08:31:13 INFO - PROCESS | 6692 | UnknownError 08:31:14 INFO - TEST-INFO | 6692: exit 0 08:31:14 INFO - Browser initialized. 08:31:14 INFO - Running cycle 1/1 for kraken test... 08:31:14 INFO - TEST-INFO | started process 6780 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjUSwq_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:31:26 INFO - PROCESS | 6780 | RSS: Main: 173015040 08:31:26 INFO - PROCESS | 6780 | 08:31:28 INFO - PROCESS | 6780 | Cycle 1(1): loaded http://localhost:45881/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45881/tests/kraken/driver.html?testName=audio-beat-detection) 08:31:29 INFO - PROCESS | 6780 | RSS: Main: 232574976 08:31:29 INFO - PROCESS | 6780 | 08:31:32 INFO - PROCESS | 6780 | Cycle 1(1): loaded http://localhost:45881/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45881/tests/kraken/driver.html?testName=audio-dft) 08:31:32 INFO - PROCESS | 6780 | RSS: Main: 216014848 08:31:32 INFO - PROCESS | 6780 | 08:31:35 INFO - PROCESS | 6780 | Cycle 1(1): loaded http://localhost:45881/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45881/tests/kraken/driver.html?testName=audio-fft) 08:31:36 INFO - PROCESS | 6780 | RSS: Main: 297746432 08:31:36 INFO - PROCESS | 6780 | 08:31:38 INFO - PROCESS | 6780 | Cycle 1(1): loaded http://localhost:45881/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45881/tests/kraken/driver.html?testName=audio-oscillator) 08:31:39 INFO - PROCESS | 6780 | RSS: Main: 208113664 08:31:39 INFO - PROCESS | 6780 | 08:31:41 INFO - PROCESS | 6780 | Cycle 1(1): loaded http://localhost:45881/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45881/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:31:41 INFO - PROCESS | 6780 | RSS: Main: 211959808 08:31:41 INFO - PROCESS | 6780 | 08:31:44 INFO - PROCESS | 6780 | Cycle 1(1): loaded http://localhost:45881/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45881/tests/kraken/driver.html?testName=imaging-darkroom) 08:31:45 INFO - PROCESS | 6780 | RSS: Main: 248586240 08:31:45 INFO - PROCESS | 6780 | 08:31:48 INFO - PROCESS | 6780 | Cycle 1(1): loaded http://localhost:45881/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45881/tests/kraken/driver.html?testName=imaging-desaturate) 08:31:49 INFO - PROCESS | 6780 | RSS: Main: 248434688 08:31:49 INFO - PROCESS | 6780 | 08:31:52 INFO - PROCESS | 6780 | Cycle 1(1): loaded http://localhost:45881/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45881/tests/kraken/driver.html?testName=json-parse-financial) 08:31:52 INFO - PROCESS | 6780 | RSS: Main: 247824384 08:31:52 INFO - PROCESS | 6780 | 08:31:54 INFO - PROCESS | 6780 | Cycle 1(1): loaded http://localhost:45881/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45881/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:31:55 INFO - PROCESS | 6780 | RSS: Main: 209776640 08:31:55 INFO - PROCESS | 6780 | 08:31:56 INFO - PROCESS | 6780 | Cycle 1(1): loaded http://localhost:45881/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45881/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:31:57 INFO - PROCESS | 6780 | RSS: Main: 217034752 08:31:57 INFO - PROCESS | 6780 | 08:31:59 INFO - PROCESS | 6780 | Cycle 1(1): loaded http://localhost:45881/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45881/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:32:00 INFO - PROCESS | 6780 | RSS: Main: 222015488 08:32:00 INFO - PROCESS | 6780 | 08:32:02 INFO - PROCESS | 6780 | Cycle 1(1): loaded http://localhost:45881/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45881/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:32:03 INFO - PROCESS | 6780 | RSS: Main: 223498240 08:32:03 INFO - PROCESS | 6780 | 08:32:06 INFO - PROCESS | 6780 | Cycle 1(1): loaded http://localhost:45881/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45881/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:32:06 INFO - PROCESS | 6780 | RSS: Main: 220647424 08:32:06 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | Cycle 1(1): loaded http://localhost:45881/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45881/tests/kraken/driver.html?testName=ai-astar) 08:32:08 INFO - PROCESS | 6780 | RSS: Main: 272183296 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | __start_tp_report 08:32:08 INFO - PROCESS | 6780 | _x_x_mozilla_page_load 08:32:08 INFO - PROCESS | 6780 | _x_x_mozilla_page_load_details 08:32:08 INFO - PROCESS | 6780 | |i|pagename|runs| 08:32:08 INFO - PROCESS | 6780 | |0;ai-astar;108;112;112;111;110;110;111;109;110;110 08:32:08 INFO - PROCESS | 6780 | |1;audio-beat-detection;146;118;121;151;121;118;118;125;152;121 08:32:08 INFO - PROCESS | 6780 | |2;audio-dft;161;161;161;160;161;163;160;162;162;162 08:32:08 INFO - PROCESS | 6780 | |3;audio-fft;76;76;76;112;77;76;77;75;115;76 08:32:08 INFO - PROCESS | 6780 | |4;audio-oscillator;82;82;127;82;83;83;104;82;82;109 08:32:08 INFO - PROCESS | 6780 | |5;imaging-gaussian-blur;117;117;117;118;117;117;117;117;117;117 08:32:08 INFO - PROCESS | 6780 | |6;imaging-darkroom;145;145;146;144;145;145;145;146;147;146 08:32:08 INFO - PROCESS | 6780 | |7;imaging-desaturate;96;96;96;95;96;95;95;95;95;95 08:32:08 INFO - PROCESS | 6780 | |8;json-parse-financial;50;50;50;50;50;51;51;52;50;51 08:32:08 INFO - PROCESS | 6780 | |9;json-stringify-tinderbox;59;60;59;60;60;60;59;60;60;60 08:32:08 INFO - PROCESS | 6780 | |10;stanford-crypto-aes;80;79;87;82;87;79;79;78;79;84 08:32:08 INFO - PROCESS | 6780 | |11;stanford-crypto-ccm;127;125;127;126;139;126;137;123;172;131 08:32:08 INFO - PROCESS | 6780 | |12;stanford-crypto-pbkdf2;167;166;166;166;166;166;166;166;169;168 08:32:08 INFO - PROCESS | 6780 | |13;stanford-crypto-sha256-iterative;67;69;66;66;67;65;64;68;67;66 08:32:08 INFO - PROCESS | 6780 | __end_tp_report 08:32:08 INFO - PROCESS | 6780 | __start_cc_report 08:32:08 INFO - PROCESS | 6780 | _x_x_mozilla_cycle_collect,1632 08:32:08 INFO - PROCESS | 6780 | __end_cc_report 08:32:08 INFO - PROCESS | 6780 | __startTimestamp1474126328585__endTimestamp 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | ------- Summary: start ------- 08:32:08 INFO - PROCESS | 6780 | Number of tests: 14 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | [#0] ai-astar Cycles:10 Average:110.30 Median:110.50 stddev:1.25 (1.1%) stddev-sans-first:1.01 08:32:08 INFO - PROCESS | 6780 | Values: 108.0 112.0 112.0 111.0 110.0 110.0 111.0 109.0 110.0 110.0 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:123.00 stddev:14.43 (11.7%) stddev-sans-first:13.94 08:32:08 INFO - PROCESS | 6780 | Values: 146.0 118.0 121.0 151.0 121.0 118.0 118.0 125.0 152.0 121.0 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 08:32:08 INFO - PROCESS | 6780 | Values: 161.0 161.0 161.0 160.0 161.0 163.0 160.0 162.0 162.0 162.0 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | [#3] audio-fft Cycles:10 Average:83.60 Median:76.00 stddev:15.78 (20.8%) stddev-sans-first:16.50 08:32:08 INFO - PROCESS | 6780 | Values: 76.0 76.0 76.0 112.0 77.0 76.0 77.0 75.0 115.0 76.0 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:82.00 stddev:16.05 (19.6%) stddev-sans-first:16.64 08:32:08 INFO - PROCESS | 6780 | Values: 82.0 82.0 127.0 82.0 83.0 83.0 104.0 82.0 82.0 109.0 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 08:32:08 INFO - PROCESS | 6780 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.84 (0.6%) stddev-sans-first:0.88 08:32:08 INFO - PROCESS | 6780 | Values: 145.0 145.0 146.0 144.0 145.0 145.0 145.0 146.0 147.0 146.0 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 08:32:08 INFO - PROCESS | 6780 | Values: 96.0 96.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.50 stddev:0.71 (1.4%) stddev-sans-first:0.73 08:32:08 INFO - PROCESS | 6780 | Values: 50.0 50.0 50.0 50.0 50.0 51.0 51.0 52.0 50.0 51.0 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 08:32:08 INFO - PROCESS | 6780 | Values: 59.0 60.0 59.0 60.0 60.0 60.0 59.0 60.0 60.0 60.0 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.00 stddev:3.44 (4.2%) stddev-sans-first:3.61 08:32:08 INFO - PROCESS | 6780 | Values: 80.0 79.0 87.0 82.0 87.0 79.0 79.0 78.0 79.0 84.0 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | [#11] stanford-crypto-ccm Cycles:10 Average:133.30 Median:129.00 stddev:14.57 (11.3%) stddev-sans-first:15.27 08:32:08 INFO - PROCESS | 6780 | Values: 127.0 125.0 127.0 126.0 139.0 126.0 137.0 123.0 172.0 131.0 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:166.00 stddev:1.07 (0.6%) stddev-sans-first:1.13 08:32:08 INFO - PROCESS | 6780 | Values: 167.0 166.0 166.0 166.0 166.0 166.0 166.0 166.0 169.0 168.0 08:32:08 INFO - PROCESS | 6780 | 08:32:08 INFO - PROCESS | 6780 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:1.43 (2.1%) stddev-sans-first:1.51 08:32:08 INFO - PROCESS | 6780 | Values: 67.0 69.0 66.0 66.0 67.0 65.0 64.0 68.0 67.0 66.0 08:32:08 INFO - PROCESS | 6780 | -------- Summary: end -------- 08:32:08 INFO - PROCESS | 6780 | 08:32:09 INFO - TEST-INFO | 6780: exit 0 08:32:09 INFO - TEST-OK | kraken | took 62034ms 08:32:09 INFO - SUITE-END | took 1178s 08:32:10 INFO - Completed test suite (00:19:38) 08:32:10 INFO - javascript benchmark 08:32:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2957.0, 3025.0471101603007, 3025.8184508929467, 3064.2952121012154, 3080.260737423018, 4989.451160372356, 5035.546800787928, 5150.8454746357575, 5186.688798672078, 5228.137357336035, 5117.130087885059, 5186.273921650963, 5192.896142077157, 5232.241325007872, 5255.500727430888, 4618.676692631517, 4658.813647454098, 4663.090697314008, 4669.533046695337, 4722.527747225267, 4780.80876764929, 4818.710877347353, 4829.7343646099625, 4834.782434790425, 4861.73260470674, 4616.561426664462, 4662.393888794435, 4674.158651442739, 4697.459792123881, 4753.90492190156, 4764.356811830427, 4766.261229509397, 4770.8330208442885, 4810.158222311082, 4839.2983017462275, 4710.599599034086, 4749.810007599692, 4757.167495688241, 4764.928526072112, 4808.302796094582, 5100.311457953166, 5144.0, 5253.106971814801, 5256.605754568384, 5323.866903327409, 5509.449055094498, 5573.108302671553, 5576.693281869496, 5580.91628625571, 5643.238162848004, 5585.832425027255, 5586.217929489874, 5602.327720673505, 5602.803901863456, 5605.103183490622, 5064.088464076428, 5237.554807841338, 5239.895202095937, 5244.711540865251, 5304.1778524329175, 5628.577856660685, 5635.0702134147405, 5635.549156067505, 5640.576956728261, 5713.371529131792, 5441.0, 5441.891162176735, 5443.727813609304, 5447.536959358499, 5450.073487507133, 5520.585956053312, 5583.888322233533, 5584.078627026576, 5586.050371436865, 5598.412166722517, 5412.945870541323, 5428.6471379361, 5476.835694929158, 5485.6434331768305, 5490.121580547093, 5508.724563771875, 5514.61397702157, 5518.19986102013, 5519.668819870821, 5569.359523654751, 4292.163028209469, 4292.420523229386, 4294.26997410441, 4340.0, 4343.131373725268, 3790.33669107905, 3795.9620403795607, 3816.542014958223, 3846.4614953906475, 3855.749376290532, 5221.373435187802, 5226.97386513065, 5229.555487783505, 5259.47405259471, 5297.046531624267, 5482.725863706799, 5491.670499770026, 5493.792618489938, 5494.478024587658, 5514.007478653881, 5067.543921047123, 5113.769880355393, 5224.660397074178, 5236.240745092016, 5262.2632831403635, 4833.4441539223435, 4852.1508735971065, 4860.465348811628, 4913.533214344631, 4916.360873086547, 5788.884222315446, 5794.652320860761, 5795.710214489343, 5798.10129429939, 5805.535557155417, 5465.316835395575, 5518.834434966958, 5546.315027097096, 5625.212470254086, 5672.744726487235, 5731.168980497721, 5759.798407055733, 5778.739956701875, 5800.8259752207505, 5857.58996870215, 5477.233187353853, 5512.6692398456225, 5515.503604675438, 5516.779328826802, 5569.805056822992, 5605.579581531483, 5608.859778505407, 5611.943880561143, 5616.269884914935, 5617.297837770278, 5216.347956505437, 5223.503767142115, 5370.489803468664, 5421.0513160196015, 5529.778808847601, 5432.103702888979, 5433.809816656398, 5439.5104440600535, 5490.3411590609385, 5499.752511137089, 5368.677879327252, 5370.221317908803, 5372.597055220952, 5377.435369286325, 5439.020976224159, 5264.447233040629, 5279.340082489689, 5431.076716958048, 5435.0, 5455.290812194548, 5376.0, 5379.865503362447, 5384.407715151252, 5386.730663466733, 5457.590680698884], "value": 5128.737195125432, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.1100532578122, 587.1339773833598, 593.0333556303225, 606.4753987800551, 607.6414915200031, 31813.68186318139, 42410.57589424105, 43526.12947741043, 43611.56388436119, 46186.307205391946, 43719.0, 44016.77991610045, 44238.672839814855, 44251.77874110625, 44311.77844110783, 9004.414713043647, 9154.49650269235, 9159.35884488086, 9165.679201227958, 9167.0, 8913.24237439815, 8949.284057275436, 8950.0, 9032.954835225848, 9033.819323613523, 8941.50821704806, 8978.23684986777, 8986.775330616721, 8990.505522196276, 9062.77343066422, 9029.683961061364, 9068.274538036942, 9078.0, 9080.318976076787, 9087.227585655228, 8834.911650883474, 8948.64205431782, 8949.105089490999, 8952.731418057469, 8955.641774329019, 41878.0, 41884.79057604723, 42266.788666056476, 42267.36598951018, 42335.36496952517, 3538.5222994895616, 3642.9271414571826, 4097.631213190827, 4118.135191609736, 4170.645495132917, 31839.52240716391, 31928.521072183703, 32291.838540807144, 32295.192620184447, 32319.838400807846, 4269.167512335096, 4352.499462561815, 4407.823687052514, 4587.632989360844, 4603.309503574491, 334989.0, 335243.0, 335735.0, 336242.0, 336664.0, 54119.0, 54329.0, 54630.726846366306, 54752.0, 54835.0, 57300.0, 57400.0, 57436.13845792317, 57498.425015750145, 57541.42458575361, 13827.723445531234, 13913.930430347784, 13946.86053139476, 13950.09324393911, 13950.372233249529, 11053.502592383362, 11073.723156921142, 11083.72290692723, 11088.722781930517, 11097.611583594535, 12924.547640832714, 15447.918645694694, 15459.92270038665, 15479.22603869802, 15701.0, 6031.276246850318, 6094.634321940609, 6208.627482350983, 6298.7795427160745, 6535.0, 721.073420654467, 723.462269502135, 838.5974732128612, 913.9771505712278, 978.2027647467291, 29557.408851822842, 30895.2276193097, 30955.226119347197, 31307.0, 31512.0, 1036.3781730961364, 1047.9161667066617, 1052.079430498314, 1071.807074726541, 1116.0067539889505, 15991.840081599035, 16289.266982985793, 16344.203619009697, 16443.753343699853, 16508.587285317964, 449243.0, 451203.0, 453361.0, 456253.0, 457245.0, 55594.72202638961, 55877.161842572394, 55902.440975590536, 55992.0, 56009.439905600426, 1148.9138314626437, 1214.423149004216, 1276.5085442104858, 1290.0969321475004, 1311.567182829664, 28797.85601072065, 31386.372272554876, 32399.0, 33605.663943360254, 34009.31981360408, 52124.0, 52686.0, 52759.20861187008, 52830.0, 52860.471395287095, 405164.0, 407743.0, 408435.0, 409419.0, 410341.0, 26681.733182667926, 27205.86397068002, 27293.863530682218, 39456.21087578175, 39770.80114599524, 46309.0, 47273.0, 47371.28943065788, 47770.0, 48293.51706483031, 37910.810445947594, 39072.2185556293, 40248.1950360997, 40880.59119408887, 41782.37326440045, 39122.0, 39291.0, 40043.3993490092, 40179.0, 41281.380779288935, 39513.0, 39608.20783584254, 39806.40290395589, 39933.40099898562, 39982.0, 32722.50916236211, 34640.0, 34791.82604086964, 35430.822845886636, 35996.28007439889, 26520.602190966765, 26955.730442695323, 26971.460570788866, 27472.538357154186, 27811.165665030083], "value": 19338.299957511896, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.7022771099987, 396.79366729300784, 408.8834682115598, 410.4131092537671, 424.4482173174874, 13114.213147211174, 14956.476523321673, 18667.25330986762, 19001.809981900176, 19458.1243844027, 18576.628467430644, 18581.721274180865, 18849.81150188498, 19141.712874306897, 19241.326553570627, 5555.0, 5617.41017193195, 5849.85375365615, 5851.327097383792, 5853.326867410238, 5651.378348381675, 5696.800611978581, 5701.942980570204, 5720.570957178207, 5798.0, 5624.606277560572, 5678.176664383683, 5689.459501347386, 5702.315722113352, 5729.341125770548, 5851.122331650266, 6028.336882942873, 6038.3659715729655, 6052.606580572271, 6058.727357268912, 5133.743312834344, 5365.946340536584, 5408.783648654088, 5874.206982057411, 5903.675297858616, 14257.572272831832, 14408.423663053465, 14434.278286085653, 14486.34811433488, 14537.92731036338, 1988.7315212446279, 2195.7255343082115, 2407.807375409963, 2660.2285337252174, 2893.1772983020774, 10265.384076955332, 10282.640107596273, 10422.791544169075, 10633.202509811717, 12078.912897839236, 1796.9550761230944, 1814.2380200315768, 1826.6346730653922, 1863.813618638139, 1937.7384053152784, 75059.49881002404, 83564.58217708873, 84238.57880710618, 84558.0, 84809.57595212046, 11248.26886252391, 11253.212275140662, 11457.14071444645, 12111.697207569881, 12227.755444891052, 15741.527754167393, 15762.527124186292, 15764.211789410483, 15799.36802527886, 15897.443589474313, 7466.887996680054, 7480.551166930001, 7641.426892983049, 7839.216078392229, 8020.398470114765, 6900.413464855443, 6988.301169883072, 7125.287471252833, 7182.102237220348, 7213.49505534618, 4728.935989402357, 4833.10587541306, 4858.368412106403, 4996.4503904570465, 4999.775010124553, 1982.5334030006832, 1986.2352994097378, 2037.7758446570865, 2047.4983629010987, 2050.5283784729595, 796.3310818912141, 876.9429987050821, 901.2069378946485, 955.0974329258784, 977.4184360305607, 17565.385211517536, 18102.823316484388, 19058.523536911413, 19071.90464047671, 19395.8060419394, 907.3240435875183, 935.9672411465566, 957.4303289542711, 985.3299756165733, 985.8521221816642, 9590.520473976412, 10236.948815255875, 10596.258261921592, 10704.250702450754, 10748.946255268673, 398559.0, 399774.0, 416840.0, 425147.0, 433611.0, 88683.0, 91838.0, 92181.53909230411, 92316.53841730749, 94607.52696236475, 979.6522234606612, 981.4356744871585, 985.408754747146, 988.8318985772436, 991.687618400216, 16384.590385240463, 16721.331146753993, 16911.0, 17832.286708531512, 17964.191611377257, 39463.210735785695, 43836.34245486384, 43874.34188487239, 44505.0, 45146.54853451424, 48100.27849582189, 55782.0, 56552.717236413555, 57009.0, 57435.71282143563, 8520.275776558814, 8689.782755431164, 9052.411593246681, 11208.983927379179, 12254.4485498151, 13170.143940643828, 13212.801807972695, 13770.586882393543, 14088.0, 14378.137311761328, 12229.143959923076, 12304.446299916346, 12383.69040773988, 12668.683282918, 12722.936385318015, 12100.757984840078, 12152.392380381123, 12206.816897746363, 12374.690632734253, 12797.552085676956, 12670.683232919251, 12759.93620031894, 12783.424745886643, 12804.0, 13067.215967042008, 12651.683707907008, 12688.492460301484, 12761.93619031899, 12848.550300739429, 13227.404766785325, 12570.245785252913, 12711.427985740478, 12777.8083328752, 12791.936040319739, 13040.478380865039], "value": 9208.28577007857, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.2048651538848, 1476.4463326252658, 1509.08700236357, 1646.6212771062656, 1787.6692811829812, 7819.061712594489, 8945.597448114835, 9190.264778817695, 9191.126842949925, 9518.85721714173, 9268.119528644786, 9345.43927364357, 9351.90648093519, 9358.859617105749, 9359.064093590638, 2378.3340664613934, 2404.026369320428, 2405.0, 2406.0736616402723, 2406.9037238510437, 2307.400075980249, 2359.327591636384, 2360.4688944987324, 2361.114582031738, 2370.8933098010543, 2287.7369102553253, 2299.0803678528555, 2317.663938728892, 2387.797037251827, 2388.5819981503255, 2244.1023590563905, 2304.043821813945, 2306.1928325086255, 2321.059970711856, 2344.5662552427857, 2299.839011269195, 2304.1244327155573, 2309.7921187093243, 2310.572544079351, 2312.063614236237, 9185.448873067637, 9247.907520924771, 9265.629374825, 9305.860412093823, 9314.301427392973, 5113.565346945514, 5331.866703332409, 5337.546308563777, 5341.626086173969, 5344.251804747339, 3500.7199424046016, 3501.57981042274, 3565.6612621800887, 3573.4639804029343, 3573.8213089345427, 1198.0774803401418, 1203.6750077479119, 1220.6826225181428, 1237.7215126596534, 1245.6014075495846, 12907.419166137546, 12957.09300348976, 12975.545855894998, 12991.675208119777, 12997.805032924514, 4400.889977750582, 4646.535346465326, 4727.763611819395, 4762.833300834454, 4770.332153498513, 10086.092251697382, 10112.241581881388, 10124.190064794799, 10127.696169114939, 10137.898621013843, 5437.891242175135, 5443.42844001382, 5449.455054494519, 5449.727513624303, 5453.072977593819, 4349.217140914602, 4476.746510976932, 4838.056578967067, 4951.331570238044, 5077.238414237894, 3949.1311911379453, 3953.486046813953, 4062.654674352654, 4070.7964601769795, 4098.692598055158, 1218.3968935377045, 1228.3244215681339, 1240.4418011894684, 1246.5325502936398, 1267.9112462127748, 796.9083555391088, 925.2829057480507, 950.5817440326234, 956.1872408452898, 1005.4017859373661, 5999.670018149044, 6153.815385538441, 6189.071639254148, 6254.718537665816, 6431.871362572722, 463.1616773639723, 464.37077759635883, 468.6883222656963, 472.36702818223745, 479.6618384039244, 4279.743215407086, 4322.675799315064, 4325.070109926381, 4373.2128216921255, 4386.188555117314, 11081.667549973514, 11308.208425410307, 11382.94308528452, 11400.828987565194, 11403.42982850854, 10878.456077196108, 11257.94371028156, 11330.37682927447, 11421.828672569918, 11452.198346115858, 8436.873446898302, 8616.956915215384, 8620.0, 8621.870671939925, 8628.22345988864, 5815.9709201453725, 5835.9708201458725, 5847.970760146172, 5882.852928676817, 6132.35610260934, 9911.603535858774, 9936.900630993887, 9955.253355998473, 9955.303128780915, 9963.850542241727, 10461.843072354073, 10499.94750026245, 10534.789304214026, 10567.89432105669, 10593.947030264799, 7285.927140728525, 7763.883541746991, 8036.1160272368825, 8045.919540804517, 8230.588470576567, 8889.422187557788, 9055.18503334703, 9064.818703625759, 9082.45505269686, 9121.45271283725, 7852.725154619561, 8113.229243221885, 8113.6754529818145, 8243.711470098518, 8332.291755200877, 8079.0, 8097.433179677366, 8192.0, 8199.42604017713, 8366.33069354438, 7404.370628496638, 7424.183339832722, 7425.70297188128, 7432.479726419315, 7523.210062943313, 5395.109806881977, 5438.063445739256, 5439.673619582838, 5484.972575137099, 5491.588130890119, 5359.62482626228, 5411.323584551931, 5462.098753705594, 5522.696251706034, 5582.218489411404], "value": 4672.774465976956, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.6128853443066, 323.5308802236756, 337.3859575572458, 339.52805600215703, 342.0678650676907, 5115.565176959963, 5203.115470370037, 5226.42509323975, 5228.764705588248, 5260.763720525673, 5202.869928251786, 5206.635535512516, 5225.6603320784125, 5229.189475631274, 5236.423993360737, 4116.794160291988, 4151.958480415203, 4163.604457576525, 4185.744276716988, 4186.099988502471, 4102.97948510257, 4119.567445418218, 4162.562930892258, 4168.62482376586, 4199.874003779892, 4114.526829414625, 4116.403121547389, 4120.072983578686, 4127.463429754128, 4129.256733787917, 4119.279126152911, 4138.379243113542, 4147.7647435000745, 4150.107726838744, 4166.604172603597, 3726.2920045191327, 3728.6644202021675, 3731.9440208396895, 3733.906652333686, 3737.626237376268, 4948.950510494921, 5076.0, 5102.311187989611, 5129.948700513021, 5151.201563757623, 4745.406824146981, 4768.47546769855, 4773.260144677581, 4773.76131193439, 4793.328933949215, 5294.285271488338, 5467.45325467454, 5468.578169675873, 5480.095784195603, 5494.642848214893, 3985.621365970228, 4013.518377794654, 4020.135670830756, 4021.5375231848125, 4044.1305119399176, 5291.86770330741, 5463.890722185573, 5605.915911261334, 5660.896125255576, 5669.149627555858, 4036.616521430459, 4101.89745256365, 4113.457453454955, 4122.732022418533, 4126.958730412717, 4179.0, 4199.853005144866, 4203.642690371292, 4206.917575799998, 4215.135897141079, 4549.203889319356, 4742.549457801503, 4748.572628463455, 4764.809407623657, 4778.474367819537, 5483.588730845203, 5489.917651235235, 5490.6431081979545, 5500.669959802424, 5514.297993982919, 1439.3882599895003, 1457.256799032501, 1464.3630020940925, 1468.0, 1469.3461409672807, 917.5432510736475, 925.949047830717, 930.8557173638097, 946.6733976778, 963.1668606655332, 4622.422197225347, 4643.744594047293, 4690.601298889633, 4705.905881882343, 4743.715377077387, 4889.677403233436, 4901.436334821541, 4901.975490122598, 4939.678920870132, 4943.233798761125, 4679.672422930362, 4680.461746899082, 4715.24556071027, 4722.48052714208, 4975.726335051534, 2947.484190266695, 2973.687762784876, 2981.120569432023, 2985.1641540368732, 2992.640883093999, 5743.62666426681, 5794.030029789892, 5813.866296072225, 5837.7956771513645, 5867.970660146672, 5536.252605898152, 5755.532339253464, 5757.992351338499, 5761.279840019997, 5769.826905192851, 5722.113072473773, 5728.656280623092, 5729.7708091676695, 5735.569832262597, 5749.464079591105, 5354.892902142014, 5616.438356164351, 5622.116590942081, 5634.802781902614, 5654.943450565606, 5480.342358916955, 5493.258410114583, 5532.3637781654015, 5562.360328562106, 5564.19319198722, 5570.637908535932, 5649.491545760901, 5651.321841379061, 5781.248437703071, 5799.695068609529, 4081.91836163281, 4094.61101195386, 4108.383742438658, 4112.568180341141, 4121.464209652726, 5607.607467477237, 5649.548036157015, 5688.77244910199, 5717.628354157133, 5724.885502290014, 5243.0, 5459.372172200247, 5461.617686761889, 5465.535429488538, 5475.6440831345835, 5389.326334208224, 5451.863703407288, 5482.150266708666, 5526.447355264441, 5605.5235304997905, 5237.528622424, 5388.919166212431, 5476.0, 5548.889022219452, 5561.499465048165, 5512.145617429305, 5514.228008078952, 5523.0, 5531.253280807039, 5534.446555344433, 5409.701671598867, 5421.484958928895, 5495.664342654276, 5505.614606977473, 5508.476694713996], "value": 4175.469573892422, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.8913618389149, 719.5107327017629, 792.9088154862193, 805.8710606302993, 816.412183228076, 15506.302216400261, 16367.263473143707, 16571.751423728652, 16699.16504174791, 16733.07968061756, 13897.096688715226, 14187.290635468236, 14324.283785810694, 14341.569752907404, 14901.92549037256, 779.7387875061861, 803.3011280186217, 807.4953154278576, 807.935365170785, 808.4340961327065, 675.5946432140726, 690.7858563845199, 793.1909452358591, 815.3192084609358, 821.930135938443, 872.8254349130168, 873.8121303919656, 876.4697358098355, 880.810625715471, 881.1276835932445, 872.3936863879607, 874.3748220022683, 875.4615911214589, 875.92992560595, 876.1457578860624, 506.82261208576705, 521.0230817217716, 613.1477246627192, 625.7747211004034, 639.763287583597, 15419.614509637237, 15451.61370965735, 15463.304151313218, 15467.149306788122, 15469.613259668486, 14125.223112728796, 14402.0, 14614.196219207939, 14678.853211467855, 14903.254837258093, 12927.935360323232, 12943.352832358438, 13299.268540230283, 13597.932010339884, 13805.240711760849, 4344.413504176927, 4456.2647163218035, 4479.0, 4606.884827879297, 4632.309217046581, 77473.22526774772, 77518.22481775223, 78640.0, 78995.0, 79207.20792792113, 10513.947430262953, 11463.656090317303, 11494.655160345203, 11591.188616796911, 11827.053835693125, 19625.0, 21193.36419907405, 21199.0, 21241.893790530947, 21255.149794008066, 7819.3744500439825, 7934.642941067666, 8012.3590112790835, 8024.034111642568, 8198.221168988937, 4335.761533115646, 4447.199504089231, 4460.843870464583, 4567.040921406476, 4711.481737008926, 3453.3438646657555, 3576.5171701819922, 3895.8052097395016, 4079.1841631673783, 4080.387941808693, 679.8912174052128, 683.4088513435942, 695.5652717051843, 697.0485287582413, 702.4310308649908, 679.4971720926479, 709.975150869717, 726.6330503096009, 743.4201322968094, 745.537766584721, 9297.860532092023, 10090.596376144871, 10204.581220493123, 10331.48342582868, 10540.472976351151, 618.1870839845678, 642.6529673976094, 647.9319671434525, 710.5026481462996, 722.2230523636608, 4804.12976123577, 4870.26945958102, 4898.174506798374, 4928.950710492921, 5144.819931302386, 110209.0, 118874.0, 120103.39948300377, 122298.0, 122566.3871680636, 86750.56624716836, 101955.0, 103420.0, 104493.0, 106505.0, 73368.0, 88095.0, 91401.0, 92266.61867050486, 93005.5349723247, 8812.518382261263, 8993.505357205417, 9017.549122543714, 9028.819423611621, 9102.180803727431, 53805.46194538005, 55890.72054639701, 55996.72001639966, 57066.0, 57191.71404142953, 61925.0, 64271.0, 73327.26672733418, 80548.0, 80628.59685701533, 13329.933350333185, 13478.123921945042, 13543.119697219649, 13565.253911034988, 13744.86255137436, 14792.778108328599, 15037.323320450825, 15178.544643660267, 15595.84404155944, 16023.599410014844, 13744.0, 14055.718885622435, 14194.3612537434, 14435.855641443452, 14480.131192128507, 13991.860081399056, 14039.438422463396, 15018.098914064754, 15179.924100379427, 15200.619984500476, 13469.326533673082, 13520.79718804199, 14026.579202623938, 14273.214973176575, 14274.785878212042, 10704.464776760975, 10927.398993055458, 10954.428976037843, 11122.0, 11458.541658333574, 9802.460864652512, 10020.198384129399, 10080.344777589433, 10842.945785271024, 11061.059809916247], "value": 7677.793498955189, "unit": "score"}], "name": "dromaeo_css", "value": 7178.408190889683, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 112.0, 111.0, 110.0, 110.0, 111.0, 109.0, 110.0, 110.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 121.0, 151.0, 121.0, 118.0, 118.0, 125.0, 152.0, 121.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 161.0, 163.0, 160.0, 162.0, 162.0, 162.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 112.0, 77.0, 76.0, 77.0, 75.0, 115.0, 76.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 127.0, 82.0, 83.0, 83.0, 104.0, 82.0, 82.0, 109.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 144.0, 145.0, 145.0, 145.0, 146.0, 147.0, 146.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 50.0, 50.0, 50.0, 51.0, 51.0, 52.0, 50.0, 51.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 60.0, 60.0, 60.0, 59.0, 60.0, 60.0, 60.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 87.0, 82.0, 87.0, 79.0, 79.0, 78.0, 79.0, 84.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 125.0, 127.0, 126.0, 139.0, 126.0, 137.0, 123.0, 172.0, 131.0], "value": 133.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 166.0, 166.0, 166.0, 166.0, 166.0, 166.0, 169.0, 168.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 66.0, 66.0, 67.0, 65.0, 64.0, 68.0, 67.0, 66.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1491.8, "alertThreshold": 2.0}]} 08:32:10 INFO - Return code: 0 08:32:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:32:10 INFO - # TBPL SUCCESS # 08:32:10 INFO - Running post-action listener: _resource_record_post_action 08:32:10 INFO - [mozharness: 2016-09-17 15:32:10.381813Z] Finished run-tests step (success) 08:32:10 INFO - Running post-run listener: _resource_record_post_run 08:32:10 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 0; Write bytes: 394162176; Read time: 0; Write time: 360752 08:32:10 INFO - TinderboxPrint: CPU usage
13.1% 08:32:10 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 08:32:10 INFO - TinderboxPrint: I/O write bytes / time
394,162,176 / 360,752 08:32:10 INFO - TinderboxPrint: CPU idle
8,294.9 (86.9%) 08:32:10 INFO - TinderboxPrint: CPU user
1,193.1 (12.5%) 08:32:10 INFO - TinderboxPrint: Swap in / out
0 / 0 08:32:10 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103870464; Read time: 0; Write time: 131876 08:32:10 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 289951744; Read time: 0; Write time: 228872 08:32:11 INFO - Running post-run listener: _upload_blobber_files 08:32:11 INFO - Blob upload gear active. 08:32:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:32:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:32:11 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:32:11 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:32:11 INFO - (blobuploader) - INFO - Open directory for files ... 08:32:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:32:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:32:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:12 INFO - (blobuploader) - INFO - Done attempting. 08:32:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:32:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:32:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:16 INFO - (blobuploader) - INFO - Done attempting. 08:32:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:32:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:32:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:16 INFO - (blobuploader) - INFO - Done attempting. 08:32:16 INFO - (blobuploader) - INFO - Iteration through files over. 08:32:16 INFO - Return code: 0 08:32:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:32:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:32:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e5172838507b18ed3e91a0393b965f99cf732bf1a81475dcd5dd9ac2b1dd497c454514ede2759e88fde02b943fb846fc21f274d9070ffa174e149a0490ab3d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb5ae8b43b9b99222289013eb9cb4de4610427d648713eb23fdb216b2f7e82cd7a958e5f13df07857b23c637708fec7d332d4bc9b71f33ac24d1afc98309dd71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e2747aa199331e34c6bce7aac4b0e0cc8c4732f1c4d5f9cc05bfe9a5f66869db99effe52a926b17eeb2272f8f7eb9e1392e1df909bd60ba1478b31e95b2e175"} 08:32:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:32:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:32:16 INFO - Contents: 08:32:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e5172838507b18ed3e91a0393b965f99cf732bf1a81475dcd5dd9ac2b1dd497c454514ede2759e88fde02b943fb846fc21f274d9070ffa174e149a0490ab3d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb5ae8b43b9b99222289013eb9cb4de4610427d648713eb23fdb216b2f7e82cd7a958e5f13df07857b23c637708fec7d332d4bc9b71f33ac24d1afc98309dd71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e2747aa199331e34c6bce7aac4b0e0cc8c4732f1c4d5f9cc05bfe9a5f66869db99effe52a926b17eeb2272f8f7eb9e1392e1df909bd60ba1478b31e95b2e175"} 08:32:16 INFO - Running post-run listener: copy_logs_to_upload_dir 08:32:16 INFO - Copying logs to upload dir... 08:32:16 INFO - mkdir: /builds/slave/test/build/upload/logs 08:32:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.016352 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-09-17 08:32:16.046002) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 08:32:16.052157) ========= 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=836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354 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/sha512/2e5172838507b18ed3e91a0393b965f99cf732bf1a81475dcd5dd9ac2b1dd497c454514ede2759e88fde02b943fb846fc21f274d9070ffa174e149a0490ab3d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb5ae8b43b9b99222289013eb9cb4de4610427d648713eb23fdb216b2f7e82cd7a958e5f13df07857b23c637708fec7d332d4bc9b71f33ac24d1afc98309dd71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e2747aa199331e34c6bce7aac4b0e0cc8c4732f1c4d5f9cc05bfe9a5f66869db99effe52a926b17eeb2272f8f7eb9e1392e1df909bd60ba1478b31e95b2e175"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e5172838507b18ed3e91a0393b965f99cf732bf1a81475dcd5dd9ac2b1dd497c454514ede2759e88fde02b943fb846fc21f274d9070ffa174e149a0490ab3d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb5ae8b43b9b99222289013eb9cb4de4610427d648713eb23fdb216b2f7e82cd7a958e5f13df07857b23c637708fec7d332d4bc9b71f33ac24d1afc98309dd71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e2747aa199331e34c6bce7aac4b0e0cc8c4732f1c4d5f9cc05bfe9a5f66869db99effe52a926b17eeb2272f8f7eb9e1392e1df909bd60ba1478b31e95b2e175"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 08:32:16.093437) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:32:16.094246) ========= 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=836cfdc75598d54cb747368e000001a9-1474125098.860023-874280354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004500 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:32:16.154379) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 08:32:16.154719) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 08:32:16.155038) ========= ========= Total master_lag: 0.16 =========