builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-069 starttime: 1474064327.9 results: success (0) buildid: 20160916133918 builduid: ac31650160924ede9fa118ed0fb68ab3 revision: 85647c98a7909549bc4e98167909a0363e4be6fc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 15:18:47.902382) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 15:18:47.903048) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 15:18:47.903311) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 15:18:48.012429) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:18:48.012700) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-16 15:18:50-- 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% 223M=0s 2016-09-16 15:18:50 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082816 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:18:48.131156) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:18:48.131460) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015303 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:18:48.200740) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:18:48.201058) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 85647c98a7909549bc4e98167909a0363e4be6fc --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 85647c98a7909549bc4e98167909a0363e4be6fc --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=05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-16 15:18:50,572 truncating revision to first 12 chars 2016-09-16 15:18:50,572 Setting DEBUG logging. 2016-09-16 15:18:50,572 attempt 1/10 2016-09-16 15:18:50,572 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/85647c98a790?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-16 15:18:50,882 unpacking tar archive at: mozilla-central-85647c98a790/testing/mozharness/ program finished with exit code 0 elapsedTime=0.655033 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:18:48.871999) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 15:18:48.872298) ========= script_repo_revision: 85647c98a7909549bc4e98167909a0363e4be6fc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 15:18:48.872695) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 15:18:48.872961) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 15:18:48.881168) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-09-16 15:18:48.881431) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:18:51 INFO - MultiFileLogger online at 20160916 15:18:51 in /builds/slave/test 15:18:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:18:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:18:51 INFO - {'append_to_log': False, 15:18:51 INFO - 'base_work_dir': '/builds/slave/test', 15:18:51 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:18:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:18:51 INFO - 'branch': 'Firefox-Non-PGO', 15:18:51 INFO - 'buildbot_json_path': 'buildprops.json', 15:18:51 INFO - 'config_files': ('talos/linux_config.py',), 15:18:51 INFO - 'default_actions': ('clobber', 15:18:51 INFO - 'read-buildbot-config', 15:18:51 INFO - 'download-and-extract', 15:18:51 INFO - 'populate-webroot', 15:18:51 INFO - 'create-virtualenv', 15:18:51 INFO - 'install', 15:18:51 INFO - 'run-tests'), 15:18:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:18:51 INFO - 'download_minidump_stackwalk': True, 15:18:51 INFO - 'download_symbols': 'ondemand', 15:18:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:18:51 INFO - 'tooltool.py': '/tools/tooltool.py', 15:18:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:18:51 INFO - '/tools/misc-python/virtualenv.py')}, 15:18:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:18:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:18:51 INFO - 'installer_path': 'installer.exe', 15:18:51 INFO - 'log_level': 'info', 15:18:51 INFO - 'log_name': 'talos', 15:18:51 INFO - 'log_to_console': True, 15:18:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:18:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:18:51 INFO - 'opt_config_files': (), 15:18:51 INFO - 'pip_index': False, 15:18:51 INFO - 'sps_profile': False, 15:18:51 INFO - 'sps_profile_interval': 0, 15:18:51 INFO - 'suite': 'dromaeojs', 15:18:51 INFO - 'system_bits': '32', 15:18:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:18:51 INFO - 'title': 'talos-linux64-ix-069', 15:18:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:18:51 INFO - 'use_talos_json': True, 15:18:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:18:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:18:51 INFO - 'work_dir': 'build'} 15:18:51 INFO - [mozharness: 2016-09-16 22:18:51.342692Z] Running clobber step. 15:18:51 INFO - Running pre-action listener: _resource_record_pre_action 15:18:51 INFO - Running main action method: clobber 15:18:51 INFO - rmtree: /builds/slave/test/build 15:18:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:18:52 INFO - Running post-action listener: _resource_record_post_action 15:18:52 INFO - [mozharness: 2016-09-16 22:18:52.317225Z] Finished clobber step (success) 15:18:52 INFO - [mozharness: 2016-09-16 22:18:52.317324Z] Running read-buildbot-config step. 15:18:52 INFO - Running pre-action listener: _resource_record_pre_action 15:18:52 INFO - Running main action method: read_buildbot_config 15:18:52 INFO - Using buildbot properties: 15:18:52 INFO - { 15:18:52 INFO - "project": "", 15:18:52 INFO - "product": "firefox", 15:18:52 INFO - "script_repo_revision": "production", 15:18:52 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 15:18:52 INFO - "repository": "", 15:18:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 15:18:52 INFO - "buildid": "20160916133918", 15:18:52 INFO - "pgo_build": "False", 15:18:52 INFO - "basedir": "/builds/slave/test", 15:18:52 INFO - "buildnumber": 645, 15:18:52 INFO - "slavename": "talos-linux64-ix-069", 15:18:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:18:52 INFO - "platform": "ubuntu64_hw", 15:18:52 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 15:18:52 INFO - "branch": "mozilla-central", 15:18:52 INFO - "repo_path": "mozilla-central", 15:18:52 INFO - "revision": "85647c98a7909549bc4e98167909a0363e4be6fc", 15:18:52 INFO - "stage_platform": "linux64", 15:18:52 INFO - "builduid": "ac31650160924ede9fa118ed0fb68ab3", 15:18:52 INFO - "slavebuilddir": "test" 15:18:52 INFO - } 15:18:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 15:18:52 INFO - Running post-action listener: _resource_record_post_action 15:18:52 INFO - [mozharness: 2016-09-16 22:18:52.319735Z] Finished read-buildbot-config step (success) 15:18:52 INFO - [mozharness: 2016-09-16 22:18:52.319825Z] Running download-and-extract step. 15:18:52 INFO - Running pre-action listener: _resource_record_pre_action 15:18:52 INFO - Running main action method: download_and_extract 15:18:52 INFO - mkdir: /builds/slave/test/build/tests 15:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:18:52 INFO - trying https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:18:52 INFO - Downloading https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:18:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:18:52 INFO - Downloaded 1361 bytes. 15:18:52 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:18:52 INFO - Using the following test package requirements: 15:18:52 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:18:52 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:18:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:18:52 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:18:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:18:52 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:18:52 INFO - u'jsshell-linux-x86_64.zip'], 15:18:52 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:18:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:18:52 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:18:52 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:18:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:18:52 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:18:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:18:52 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:18:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:18:52 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:18:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:18:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:18:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:18:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:18:52 INFO - Fetch https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:18:53 INFO - Expected file size: 20365039 15:18:57 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 15:18:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:18:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:18:57 INFO - Fetch https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:18:58 INFO - Expected file size: 20365039 15:19:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:19:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:19:03 INFO - Fetch https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 15:19:03 INFO - Expected file size: 14414048 15:19: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')]} 15:19:03 INFO - trying https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:19:03 INFO - Downloading https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:19:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:19:05 INFO - Downloaded 55594889 bytes. 15:19:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:19:05 INFO - mkdir: /builds/slave/test/properties 15:19:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:19:05 INFO - Writing to file /builds/slave/test/properties/build_url 15:19:05 INFO - Contents: 15:19:05 INFO - build_url:https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:19:05 INFO - Running post-action listener: _resource_record_post_action 15:19:05 INFO - Running post-action listener: set_extra_try_arguments 15:19:05 INFO - [mozharness: 2016-09-16 22:19:05.769429Z] Finished download-and-extract step (success) 15:19:05 INFO - [mozharness: 2016-09-16 22:19:05.769728Z] Running populate-webroot step. 15:19:05 INFO - Running pre-action listener: _resource_record_pre_action 15:19:05 INFO - Running main action method: populate_webroot 15:19:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:19:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:19:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:19:05 INFO - '--tppagecycles', 15:19:05 INFO - '7'], 15:19:05 INFO - 'tests': ['tsvgm']}}, 15:19:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:19:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:19:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:19:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:19:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:19:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:19:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:05 INFO - 'talos_options': ['--disable-e10s'], 15:19:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:19:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:19:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:05 INFO - 'talos_options': ['--disable-e10s'], 15:19:05 INFO - 'tests': ['damp', 'tps']}, 15:19:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:05 INFO - 'tests': ['damp', 'tps']}, 15:19:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:19:05 INFO - 'tests': ['dromaeo_dom']}, 15:19:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:19:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:19:05 INFO - 'tests': ['basic_compositor_video']}, 15:19:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:19:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:19:05 INFO - 'tests': ['a11yr', 15:19:05 INFO - 'ts_paint', 15:19:05 INFO - 'tpaint', 15:19:05 INFO - 'sessionrestore', 15:19:05 INFO - 'sessionrestore_no_auto_restore', 15:19:05 INFO - 'tabpaint']}, 15:19:05 INFO - 'other-e10s': {'tests': ['a11yr', 15:19:05 INFO - 'ts_paint', 15:19:05 INFO - 'tpaint', 15:19:05 INFO - 'sessionrestore', 15:19:05 INFO - 'sessionrestore_no_auto_restore', 15:19:05 INFO - 'tabpaint']}, 15:19:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:19:05 INFO - 'ts_paint', 15:19:05 INFO - 'tpaint', 15:19:05 INFO - 'sessionrestore', 15:19:05 INFO - 'sessionrestore_no_auto_restore', 15:19:05 INFO - 'tabpaint']}, 15:19:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:19:05 INFO - 'ts_paint', 15:19:05 INFO - 'tpaint', 15:19:05 INFO - 'sessionrestore', 15:19:05 INFO - 'sessionrestore_no_auto_restore', 15:19:05 INFO - 'tabpaint']}, 15:19:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:19:05 INFO - 'tests': ['a11yr', 15:19:05 INFO - 'ts_paint', 15:19:05 INFO - 'tpaint', 15:19:05 INFO - 'sessionrestore', 15:19:05 INFO - 'sessionrestore_no_auto_restore', 15:19:05 INFO - 'tabpaint']}, 15:19:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:19:05 INFO - 'tests': ['a11yr', 15:19:05 INFO - 'ts_paint', 15:19:05 INFO - 'tpaint', 15:19:05 INFO - 'sessionrestore', 15:19:05 INFO - 'sessionrestore_no_auto_restore', 15:19:05 INFO - 'tabpaint']}, 15:19:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:19:05 INFO - 'tests': ['tsvgx', 15:19:05 INFO - 'tsvgr_opacity', 15:19:05 INFO - 'tart', 15:19:05 INFO - 'tscrollx', 15:19:05 INFO - 'cart']}, 15:19:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:19:05 INFO - 'tsvgr_opacity', 15:19:05 INFO - 'tart', 15:19:05 INFO - 'tscrollx', 15:19:05 INFO - 'cart']}, 15:19:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:05 INFO - 'talos_options': ['--disable-e10s'], 15:19:05 INFO - 'tests': ['tp5o']}, 15:19:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:05 INFO - 'tests': ['tp5o']}, 15:19:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:05 INFO - 'talos_options': ['--disable-e10s', 15:19:05 INFO - '--xperf_path', 15:19:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:19:05 INFO - 'tests': ['tp5n']}, 15:19:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:05 INFO - 'talos_options': ['--xperf_path', 15:19:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:19:05 INFO - 'tests': ['tp5n']}}, 15:19:05 INFO - 'talos.zip': {'path': '', 15:19:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:19:05 INFO - Running post-action listener: _resource_record_post_action 15:19:05 INFO - [mozharness: 2016-09-16 22:19:05.789383Z] Finished populate-webroot step (success) 15:19:05 INFO - [mozharness: 2016-09-16 22:19:05.789476Z] Running create-virtualenv step. 15:19:05 INFO - Running pre-action listener: _resource_record_pre_action 15:19:05 INFO - Running main action method: create_virtualenv 15:19:05 INFO - Creating virtualenv /builds/slave/test/build/venv 15:19:05 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 15:19:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:19:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:19:05 INFO - Using real prefix '/usr' 15:19:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:19:06 INFO - Installing distribute.............................................................................................................................................................................................done. 15:19:08 INFO - Installing pip.................done. 15:19:08 INFO - Return code: 0 15:19:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:19:08 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')]} 15:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:08 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')]} 15:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:08 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 0x7f3f63fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1580ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157df60>, '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 0x157ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157f1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', '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 15:19:08 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 15:19:08 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 15:19:08 INFO - Using env: {'DISPLAY': ':0', 15:19:08 INFO - 'HOME': '/home/cltbld', 15:19:08 INFO - 'LANG': 'en_US.UTF-8', 15:19:08 INFO - 'LANGUAGE': 'en_US:en', 15:19:08 INFO - 'LOGNAME': 'cltbld', 15:19:08 INFO - 'MAIL': '/var/mail/cltbld', 15:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:08 INFO - 'MOZ_NO_REMOTE': '1', 15:19:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:08 INFO - 'NO_EM_RESTART': '1', 15:19:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:08 INFO - 'PWD': '/builds/slave/test', 15:19:08 INFO - 'SHELL': '/bin/bash', 15:19:08 INFO - 'SHLVL': '1', 15:19:08 INFO - 'TERM': 'linux', 15:19:08 INFO - 'TMOUT': '86400', 15:19:08 INFO - 'USER': 'cltbld', 15:19:08 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', 15:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:08 INFO - '_': '/tools/buildbot/bin/python'} 15:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:08 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 15:19:08 INFO - Cleaning up... 15:19:08 INFO - Return code: 0 15:19:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:19:08 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')]} 15:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:08 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')]} 15:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:08 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 0x7f3f63fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1580ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157df60>, '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 0x157ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157f1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', '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 15:19:08 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 15:19:08 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 15:19:08 INFO - Using env: {'DISPLAY': ':0', 15:19:08 INFO - 'HOME': '/home/cltbld', 15:19:08 INFO - 'LANG': 'en_US.UTF-8', 15:19:08 INFO - 'LANGUAGE': 'en_US:en', 15:19:08 INFO - 'LOGNAME': 'cltbld', 15:19:08 INFO - 'MAIL': '/var/mail/cltbld', 15:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:08 INFO - 'MOZ_NO_REMOTE': '1', 15:19:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:08 INFO - 'NO_EM_RESTART': '1', 15:19:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:08 INFO - 'PWD': '/builds/slave/test', 15:19:08 INFO - 'SHELL': '/bin/bash', 15:19:08 INFO - 'SHLVL': '1', 15:19:08 INFO - 'TERM': 'linux', 15:19:08 INFO - 'TMOUT': '86400', 15:19:08 INFO - 'USER': 'cltbld', 15:19:08 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', 15:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:08 INFO - '_': '/tools/buildbot/bin/python'} 15:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:08 INFO - Downloading/unpacking psutil>=3.1.1 15:19:08 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 15:19:08 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 15:19:08 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 15:19:08 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 15:19:08 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 15:19:08 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 15:19:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:19:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:19:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:19:10 INFO - Installing collected packages: psutil 15:19:10 INFO - Running setup.py install for psutil 15:19:10 INFO - building 'psutil._psutil_linux' extension 15:19:10 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 15:19:11 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 15:19:11 INFO - building 'psutil._psutil_posix' extension 15:19:11 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 15:19:11 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 15:19:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:19:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:19:11 INFO - Successfully installed psutil 15:19:11 INFO - Cleaning up... 15:19:11 INFO - Return code: 0 15:19:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:19:11 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')]} 15:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:11 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')]} 15:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:11 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 0x7f3f63fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1580ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157df60>, '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 0x157ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157f1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', '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 15:19:11 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 15:19:11 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 15:19:11 INFO - Using env: {'DISPLAY': ':0', 15:19:11 INFO - 'HOME': '/home/cltbld', 15:19:11 INFO - 'LANG': 'en_US.UTF-8', 15:19:11 INFO - 'LANGUAGE': 'en_US:en', 15:19:11 INFO - 'LOGNAME': 'cltbld', 15:19:11 INFO - 'MAIL': '/var/mail/cltbld', 15:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:11 INFO - 'MOZ_NO_REMOTE': '1', 15:19:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:11 INFO - 'NO_EM_RESTART': '1', 15:19:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:11 INFO - 'PWD': '/builds/slave/test', 15:19:11 INFO - 'SHELL': '/bin/bash', 15:19:11 INFO - 'SHLVL': '1', 15:19:11 INFO - 'TERM': 'linux', 15:19:11 INFO - 'TMOUT': '86400', 15:19:11 INFO - 'USER': 'cltbld', 15:19:11 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', 15:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:11 INFO - '_': '/tools/buildbot/bin/python'} 15:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:19: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 15:19: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 15:19: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 15:19: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 15:19: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 15:19: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 15:19:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:19:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:19:13 INFO - Installing collected packages: mozsystemmonitor 15:19:13 INFO - Running setup.py install for mozsystemmonitor 15:19:13 INFO - Successfully installed mozsystemmonitor 15:19:13 INFO - Cleaning up... 15:19:13 INFO - Return code: 0 15:19:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:19: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')]} 15:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19: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')]} 15:19:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3f63fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1580ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157df60>, '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 0x157ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157f1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', '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 15:19: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==2.5.1'] in /builds/slave/test/build 15:19: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==2.5.1 15:19:13 INFO - Using env: {'DISPLAY': ':0', 15:19:13 INFO - 'HOME': '/home/cltbld', 15:19:13 INFO - 'LANG': 'en_US.UTF-8', 15:19:13 INFO - 'LANGUAGE': 'en_US:en', 15:19:13 INFO - 'LOGNAME': 'cltbld', 15:19:13 INFO - 'MAIL': '/var/mail/cltbld', 15:19:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:13 INFO - 'MOZ_NO_REMOTE': '1', 15:19:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:13 INFO - 'NO_EM_RESTART': '1', 15:19:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:13 INFO - 'PWD': '/builds/slave/test', 15:19:13 INFO - 'SHELL': '/bin/bash', 15:19:13 INFO - 'SHLVL': '1', 15:19:13 INFO - 'TERM': 'linux', 15:19:13 INFO - 'TMOUT': '86400', 15:19:13 INFO - 'USER': 'cltbld', 15:19:13 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', 15:19:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:13 INFO - '_': '/tools/buildbot/bin/python'} 15:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:13 INFO - Downloading/unpacking jsonschema==2.5.1 15:19:13 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 15:19:13 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 15:19:13 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 15:19:13 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 15:19:13 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 15:19:13 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 15:19:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:19:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:19:15 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 15:19:15 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 15:19:15 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 15:19:15 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 15:19:15 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 15:19:15 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 15:19:15 INFO - Downloading functools32-3.2.3-2.tar.gz 15:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:19:15 INFO - warning: no files found matching '*.txt' 15:19:15 INFO - no previously-included directories found matching 'build' 15:19:15 INFO - no previously-included directories found matching 'dist' 15:19:15 INFO - no previously-included directories found matching '.git*' 15:19:16 INFO - Installing collected packages: jsonschema, functools32 15:19:16 INFO - Running setup.py install for functools32 15:19:16 INFO - warning: no files found matching '*.txt' 15:19:16 INFO - no previously-included directories found matching 'build' 15:19:16 INFO - no previously-included directories found matching 'dist' 15:19:16 INFO - no previously-included directories found matching '.git*' 15:19:16 INFO - Successfully installed jsonschema functools32 15:19:16 INFO - Cleaning up... 15:19:16 INFO - Return code: 0 15:19:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:16 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 0x7f3f63fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1580ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157df60>, '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 0x157ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157f1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', '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 15:19:16 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 15:19:16 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 15:19:16 INFO - Using env: {'DISPLAY': ':0', 15:19:16 INFO - 'HOME': '/home/cltbld', 15:19:16 INFO - 'LANG': 'en_US.UTF-8', 15:19:16 INFO - 'LANGUAGE': 'en_US:en', 15:19:16 INFO - 'LOGNAME': 'cltbld', 15:19:16 INFO - 'MAIL': '/var/mail/cltbld', 15:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:16 INFO - 'MOZ_NO_REMOTE': '1', 15:19:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:16 INFO - 'NO_EM_RESTART': '1', 15:19:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:16 INFO - 'PWD': '/builds/slave/test', 15:19:16 INFO - 'SHELL': '/bin/bash', 15:19:16 INFO - 'SHLVL': '1', 15:19:16 INFO - 'TERM': 'linux', 15:19:16 INFO - 'TMOUT': '86400', 15:19:16 INFO - 'USER': 'cltbld', 15:19:16 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', 15:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:16 INFO - '_': '/tools/buildbot/bin/python'} 15:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:19:16 INFO - Cleaning up... 15:19:16 INFO - Return code: 0 15:19:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:16 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 0x7f3f63fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1580ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157df60>, '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 0x157ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157f1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', '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 15:19:16 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 15:19:16 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 15:19:16 INFO - Using env: {'DISPLAY': ':0', 15:19:16 INFO - 'HOME': '/home/cltbld', 15:19:16 INFO - 'LANG': 'en_US.UTF-8', 15:19:16 INFO - 'LANGUAGE': 'en_US:en', 15:19:16 INFO - 'LOGNAME': 'cltbld', 15:19:16 INFO - 'MAIL': '/var/mail/cltbld', 15:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:16 INFO - 'MOZ_NO_REMOTE': '1', 15:19:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:16 INFO - 'NO_EM_RESTART': '1', 15:19:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:16 INFO - 'PWD': '/builds/slave/test', 15:19:16 INFO - 'SHELL': '/bin/bash', 15:19:16 INFO - 'SHLVL': '1', 15:19:16 INFO - 'TERM': 'linux', 15:19:16 INFO - 'TMOUT': '86400', 15:19:16 INFO - 'USER': 'cltbld', 15:19:16 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', 15:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:16 INFO - '_': '/tools/buildbot/bin/python'} 15:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:16 INFO - Downloading/unpacking blobuploader==1.2.4 15:19:16 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 15:19:16 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 15:19:16 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 15:19:16 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 15:19:16 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 15:19:16 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 15:19:18 INFO - Downloading blobuploader-1.2.4.tar.gz 15:19:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:19:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:19:18 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 15:19:18 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 15:19:18 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 15:19:18 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 15:19:18 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 15:19:18 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 15:19:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:19:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:19:19 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 15:19:19 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 15:19:19 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 15:19:19 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 15:19:19 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 15:19:19 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 15:19:19 INFO - Downloading docopt-0.6.1.tar.gz 15:19:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:19:19 INFO - Installing collected packages: blobuploader, requests, docopt 15:19:19 INFO - Running setup.py install for blobuploader 15:19:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:19:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:19:19 INFO - Running setup.py install for requests 15:19:19 INFO - Running setup.py install for docopt 15:19:19 INFO - Successfully installed blobuploader requests docopt 15:19:19 INFO - Cleaning up... 15:19:19 INFO - Return code: 0 15:19:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19:19 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')]} 15:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:19 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')]} 15:19:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:19 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 0x7f3f63fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1580ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157df60>, '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 0x157ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157f1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', '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 15:19:19 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 15:19:19 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 15:19:19 INFO - Using env: {'DISPLAY': ':0', 15:19:19 INFO - 'HOME': '/home/cltbld', 15:19:19 INFO - 'LANG': 'en_US.UTF-8', 15:19:19 INFO - 'LANGUAGE': 'en_US:en', 15:19:19 INFO - 'LOGNAME': 'cltbld', 15:19:19 INFO - 'MAIL': '/var/mail/cltbld', 15:19:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:19 INFO - 'MOZ_NO_REMOTE': '1', 15:19:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:19 INFO - 'NO_EM_RESTART': '1', 15:19:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:19 INFO - 'PWD': '/builds/slave/test', 15:19:19 INFO - 'SHELL': '/bin/bash', 15:19:19 INFO - 'SHLVL': '1', 15:19:19 INFO - 'TERM': 'linux', 15:19:19 INFO - 'TMOUT': '86400', 15:19:19 INFO - 'USER': 'cltbld', 15:19:19 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', 15:19:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:19 INFO - '_': '/tools/buildbot/bin/python'} 15:19:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:19:20 INFO - Running setup.py (path:/tmp/pip-DWYiC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:19:20 INFO - Running setup.py (path:/tmp/pip-Sn3jl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:19:20 INFO - Running setup.py (path:/tmp/pip-4mIxxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:19:20 INFO - Running setup.py (path:/tmp/pip-TJLIki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:19:20 INFO - Running setup.py (path:/tmp/pip-OQ0vYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:19:20 INFO - Running setup.py (path:/tmp/pip-xrlypL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:19:20 INFO - Running setup.py (path:/tmp/pip-5gEVwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:19:20 INFO - Running setup.py (path:/tmp/pip-64TtO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:19:20 INFO - Running setup.py (path:/tmp/pip-AY5gBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:19:20 INFO - Running setup.py (path:/tmp/pip-YM45rE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:19:20 INFO - Running setup.py (path:/tmp/pip-9DFXKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:19:20 INFO - Running setup.py (path:/tmp/pip-dsnStN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:19:21 INFO - Running setup.py (path:/tmp/pip-cYOZ88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:19:21 INFO - Running setup.py (path:/tmp/pip-apPXhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:21 INFO - Running setup.py (path:/tmp/pip-RhGXxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:19:21 INFO - Running setup.py (path:/tmp/pip-a36GHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:19:21 INFO - Running setup.py (path:/tmp/pip-El2Bz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:19:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:19:21 INFO - Running setup.py install for manifestparser 15:19:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:19:21 INFO - Running setup.py install for mozcrash 15:19:21 INFO - Running setup.py install for mozdebug 15:19:21 INFO - Running setup.py install for mozdevice 15:19:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:19:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:19:21 INFO - Running setup.py install for mozfile 15:19:21 INFO - Running setup.py install for mozhttpd 15:19:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:19:21 INFO - Running setup.py install for mozinfo 15:19:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:19:22 INFO - Running setup.py install for mozInstall 15:19:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:19:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:19:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:19:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:19:22 INFO - Running setup.py install for mozleak 15:19:22 INFO - Running setup.py install for mozlog 15:19:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:19:22 INFO - Running setup.py install for moznetwork 15:19:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:19:22 INFO - Running setup.py install for mozprocess 15:19:22 INFO - Running setup.py install for mozprofile 15:19:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:19:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:19:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:19:22 INFO - Running setup.py install for mozrunner 15:19:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:19:22 INFO - Running setup.py install for mozscreenshot 15:19:22 INFO - Running setup.py install for moztest 15:19:23 INFO - Running setup.py install for mozversion 15:19:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:19:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:19:23 INFO - Cleaning up... 15:19:23 INFO - Return code: 0 15:19:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19:23 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')]} 15:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:23 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')]} 15:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:23 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 0x7f3f63fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1580ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157df60>, '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 0x157ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157f1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', '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 15:19:23 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 15:19:23 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 15:19:23 INFO - Using env: {'DISPLAY': ':0', 15:19:23 INFO - 'HOME': '/home/cltbld', 15:19:23 INFO - 'LANG': 'en_US.UTF-8', 15:19:23 INFO - 'LANGUAGE': 'en_US:en', 15:19:23 INFO - 'LOGNAME': 'cltbld', 15:19:23 INFO - 'MAIL': '/var/mail/cltbld', 15:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:23 INFO - 'MOZ_NO_REMOTE': '1', 15:19:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:23 INFO - 'NO_EM_RESTART': '1', 15:19:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:23 INFO - 'PWD': '/builds/slave/test', 15:19:23 INFO - 'SHELL': '/bin/bash', 15:19:23 INFO - 'SHLVL': '1', 15:19:23 INFO - 'TERM': 'linux', 15:19:23 INFO - 'TMOUT': '86400', 15:19:23 INFO - 'USER': 'cltbld', 15:19:23 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', 15:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:23 INFO - '_': '/tools/buildbot/bin/python'} 15:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:19:23 INFO - Running setup.py (path:/tmp/pip-PlJ0v2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:19:23 INFO - Running setup.py (path:/tmp/pip-UjgqVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:19:23 INFO - Running setup.py (path:/tmp/pip-TwQxRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:19:23 INFO - Running setup.py (path:/tmp/pip-WSKgcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:19:23 INFO - Running setup.py (path:/tmp/pip-7mWoz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:19:23 INFO - Running setup.py (path:/tmp/pip-V2YpAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:19:23 INFO - Running setup.py (path:/tmp/pip-PH033P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:19:23 INFO - Running setup.py (path:/tmp/pip-H8LM84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:19:23 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)) 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:19:23 INFO - Running setup.py (path:/tmp/pip-H2n2sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:19:24 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)) 15:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:19:24 INFO - Running setup.py (path:/tmp/pip-aoih9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:19:24 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)) 15:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:19:24 INFO - Running setup.py (path:/tmp/pip-fpB8Ox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:19:24 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)) 15:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:19:24 INFO - Running setup.py (path:/tmp/pip-dbi5Jv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:19:24 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)) 15:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:19:24 INFO - Running setup.py (path:/tmp/pip-Y7SjZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:19:24 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)) 15:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:19:24 INFO - Running setup.py (path:/tmp/pip-_ppf6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:19:24 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)) 15:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:24 INFO - Running setup.py (path:/tmp/pip-lCZ7mL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:24 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)) 15:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:19:24 INFO - Running setup.py (path:/tmp/pip-O_nvtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:19:24 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)) 15:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:19:24 INFO - Running setup.py (path:/tmp/pip-Z6vrTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:19:24 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 15:19:24 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 15:19:24 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 15:19:24 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 15:19:24 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 15:19:24 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 15:19:26 INFO - Downloading blessings-1.6.tar.gz 15:19:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:19:26 INFO - Installing collected packages: blessings 15:19:26 INFO - Running setup.py install for blessings 15:19:26 INFO - Successfully installed blessings 15:19:26 INFO - Cleaning up... 15:19:26 INFO - Return code: 0 15:19:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:19:26 INFO - Reading from file tmpfile_stdout 15:19:26 INFO - Current package versions: 15:19:26 INFO - argparse == 1.2.1 15:19:26 INFO - blessings == 1.6 15:19:26 INFO - blobuploader == 1.2.4 15:19:26 INFO - docopt == 0.6.1 15:19:26 INFO - functools32 == 3.2.3-2 15:19:26 INFO - jsonschema == 2.5.1 15:19:26 INFO - manifestparser == 1.1 15:19:26 INFO - mozInstall == 1.12 15:19:26 INFO - mozcrash == 0.17 15:19:26 INFO - mozdebug == 0.1 15:19:26 INFO - mozdevice == 0.48 15:19:26 INFO - mozfile == 1.2 15:19:26 INFO - mozhttpd == 0.7 15:19:26 INFO - mozinfo == 0.9 15:19:26 INFO - mozleak == 0.1 15:19:26 INFO - mozlog == 3.4 15:19:26 INFO - moznetwork == 0.27 15:19:26 INFO - mozprocess == 0.23 15:19:26 INFO - mozprofile == 0.28 15:19:26 INFO - mozrunner == 6.12 15:19:26 INFO - mozscreenshot == 0.1 15:19:26 INFO - mozsystemmonitor == 0.3 15:19:26 INFO - moztest == 0.7 15:19:26 INFO - mozversion == 1.4 15:19:26 INFO - psutil == 3.1.1 15:19:26 INFO - requests == 1.2.3 15:19:26 INFO - wsgiref == 0.1.2 15:19:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:26 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 0x7f3f63fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1580ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157df60>, '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 0x157ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157f1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', '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 15:19:26 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 15:19:26 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 15:19:26 INFO - Using env: {'DISPLAY': ':0', 15:19:26 INFO - 'HOME': '/home/cltbld', 15:19:26 INFO - 'LANG': 'en_US.UTF-8', 15:19:26 INFO - 'LANGUAGE': 'en_US:en', 15:19:26 INFO - 'LOGNAME': 'cltbld', 15:19:26 INFO - 'MAIL': '/var/mail/cltbld', 15:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:26 INFO - 'MOZ_NO_REMOTE': '1', 15:19:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:26 INFO - 'NO_EM_RESTART': '1', 15:19:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:26 INFO - 'PWD': '/builds/slave/test', 15:19:26 INFO - 'SHELL': '/bin/bash', 15:19:26 INFO - 'SHLVL': '1', 15:19:26 INFO - 'TERM': 'linux', 15:19:26 INFO - 'TMOUT': '86400', 15:19:26 INFO - 'USER': 'cltbld', 15:19:26 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', 15:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:26 INFO - '_': '/tools/buildbot/bin/python'} 15:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 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)) 15:19:27 INFO - Cleaning up... 15:19:27 INFO - Return code: 0 15:19:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:19:27 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')]} 15:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:27 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')]} 15:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:27 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 0x7f3f63fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1580ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157df60>, '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 0x157ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157f1d0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', '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 15:19:27 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 15:19:27 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 15:19:27 INFO - Using env: {'DISPLAY': ':0', 15:19:27 INFO - 'HOME': '/home/cltbld', 15:19:27 INFO - 'LANG': 'en_US.UTF-8', 15:19:27 INFO - 'LANGUAGE': 'en_US:en', 15:19:27 INFO - 'LOGNAME': 'cltbld', 15:19:27 INFO - 'MAIL': '/var/mail/cltbld', 15:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:27 INFO - 'MOZ_NO_REMOTE': '1', 15:19:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:27 INFO - 'NO_EM_RESTART': '1', 15:19:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:27 INFO - 'PWD': '/builds/slave/test', 15:19:27 INFO - 'SHELL': '/bin/bash', 15:19:27 INFO - 'SHLVL': '1', 15:19:27 INFO - 'TERM': 'linux', 15:19:27 INFO - 'TMOUT': '86400', 15:19:27 INFO - 'USER': 'cltbld', 15:19:27 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', 15:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:27 INFO - '_': '/tools/buildbot/bin/python'} 15:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:19:27 INFO - Cleaning up... 15:19:27 INFO - Return code: 0 15:19:27 INFO - Running post-action listener: _resource_record_post_action 15:19:27 INFO - Running post-action listener: _start_resource_monitoring 15:19:27 INFO - Starting resource monitoring. 15:19:27 INFO - [mozharness: 2016-09-16 22:19:27.452163Z] Finished create-virtualenv step (success) 15:19:27 INFO - [mozharness: 2016-09-16 22:19:27.452677Z] Running install step. 15:19:27 INFO - Running pre-action listener: _resource_record_pre_action 15:19:27 INFO - Running main action method: install 15:19:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:19:27 INFO - Reading from file tmpfile_stdout 15:19:27 INFO - Detecting whether we're running mozinstall >=1.0... 15:19:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:19:27 INFO - Reading from file tmpfile_stdout 15:19:27 INFO - Output received: 15:19:27 INFO - Usage: mozinstall [options] installer 15:19:27 INFO - Options: 15:19:27 INFO - -h, --help show this help message and exit 15:19:27 INFO - -d DEST, --destination=DEST 15:19:27 INFO - Directory to install application into. [default: 15:19:27 INFO - "/builds/slave/test"] 15:19:27 INFO - --app=APP Application being installed. [default: firefox] 15:19:27 INFO - mkdir: /builds/slave/test/build/application 15:19:27 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'] 15:19:27 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 15:19:43 INFO - Reading from file tmpfile_stdout 15:19:43 INFO - Output received: 15:19:43 INFO - /builds/slave/test/build/application/firefox/firefox 15:19:43 INFO - Running post-action listener: _resource_record_post_action 15:19:43 INFO - [mozharness: 2016-09-16 22:19:43.801812Z] Finished install step (success) 15:19:43 INFO - [mozharness: 2016-09-16 22:19:43.802147Z] Running run-tests step. 15:19:43 INFO - Running pre-action listener: _resource_record_pre_action 15:19:43 INFO - Running main action method: run_tests 15:19:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:19:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:19:43 INFO - Python 2.7.3 15:19:43 INFO - Return code: 0 15:19:43 INFO - grabbing minidump binary from tooltool 15:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:43 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 0x157df60>, '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 0x157ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157f1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:19:43 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 15:19:43 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 15:19:43 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 15:19:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:19:43 INFO - Return code: 0 15:19:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:19:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:19:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:19:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:19:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:19:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 15:19:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 15:19:43 INFO - Using env: {'DISPLAY': ':0', 15:19:43 INFO - 'HOME': '/home/cltbld', 15:19:43 INFO - 'LANG': 'en_US.UTF-8', 15:19:43 INFO - 'LANGUAGE': 'en_US:en', 15:19:43 INFO - 'LOGNAME': 'cltbld', 15:19:43 INFO - 'MAIL': '/var/mail/cltbld', 15:19:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:19:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:43 INFO - 'MOZ_NO_REMOTE': '1', 15:19:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:19:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:43 INFO - 'NO_EM_RESTART': '1', 15:19:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:43 INFO - 'PWD': '/builds/slave/test', 15:19:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:19:43 INFO - 'SHELL': '/bin/bash', 15:19:43 INFO - 'SHLVL': '1', 15:19:43 INFO - 'TERM': 'linux', 15:19:43 INFO - 'TMOUT': '86400', 15:19:43 INFO - 'USER': 'cltbld', 15:19:43 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077', 15:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:43 INFO - '_': '/tools/buildbot/bin/python'} 15:19:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 15:19:44 INFO - mozversion application_buildid: 20160916133918 15:19:44 INFO - mozversion application_changeset: 85647c98a7909549bc4e98167909a0363e4be6fc 15:19:44 INFO - mozversion application_display_name: Nightly 15:19:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:19:44 INFO - mozversion application_name: Firefox 15:19:44 INFO - mozversion application_remotingname: firefox 15:19:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:19:44 INFO - mozversion application_vendor: Mozilla 15:19:44 INFO - mozversion application_version: 51.0a1 15:19:44 INFO - mozversion platform_buildid: 20160916133918 15:19:44 INFO - mozversion platform_changeset: 85647c98a7909549bc4e98167909a0363e4be6fc 15:19:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:19:44 INFO - mozversion platform_version: 51.0a1 15:19:44 INFO - using testdate: 1474064384 15:19:44 INFO - actual date: 1474064384 15:19:44 INFO - starting webserver on 'localhost:58280' 15:19:44 INFO - SUITE-START | Running 2 tests 15:19:44 INFO - TEST-START | dromaeo_css 15:19:44 INFO - operating with platform_type : linux_ 15:19:44 INFO - Initialising browser for dromaeo_css test... 15:19:44 INFO - TEST-INFO | started process 7142 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY5APZq/profile http://localhost:58280/getInfo.html) 15:19:48 INFO - PROCESS | 7142 | __metrics Screen width/height:1600/1200 15:19:48 INFO - PROCESS | 7142 | colorDepth:24 15:19:48 INFO - PROCESS | 7142 | Browser inner width/height: 1024/697 15:19:48 INFO - PROCESS | 7142 | __metrics 15:19:49 INFO - PROCESS | 7142 | 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" 15:19:49 INFO - PROCESS | 7142 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:19:49 INFO - PROCESS | 7142 | console.error: PushService: 15:19:49 INFO - PROCESS | 7142 | stateChangeProcessEnqueue: Error transitioning state 15:19:49 INFO - PROCESS | 7142 | UnknownError 15:19:50 INFO - TEST-INFO | 7142: exit 0 15:19:50 INFO - Browser initialized. 15:19:50 INFO - Running cycle 1/1 for dromaeo_css test... 15:19:50 INFO - TEST-INFO | started process 7225 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY5APZq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:20:02 INFO - PROCESS | 7225 | RSS: Main: 174469120 15:20:02 INFO - PROCESS | 7225 | 15:20:12 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 15:20:37 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 7.25037 seconds. 15:20:39 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 15:20:53 INFO - PROCESS | 7225 | 1474064453414 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] 15:20:55 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:21:12 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:21:16 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:21:25 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 15:21:30 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:21:32 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:21:38 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:21:41 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 15:21:43 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.10006 seconds. 15:21:49 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 15:21:53 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.06667 seconds. 15:21:57 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:21:59 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 15:22:10 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 15:22:15 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:22:16 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 2.03343 seconds. 15:22:27 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:22:34 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:22:40 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:22:42 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:22:51 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:22:56 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 15:22:58 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:23:01 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 2.01668 seconds. 15:23:01 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 2.01668 seconds. 15:23:07 INFO - PROCESS | 7225 | Cycle 1(1): loaded http://localhost:58280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58280/tests/dromaeo/cssquery-ext.html) 15:23:08 INFO - PROCESS | 7225 | RSS: Main: 285732864 15:23:08 INFO - PROCESS | 7225 | 15:23:11 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 15:23:20 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 15:23:41 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:23:47 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:24:00 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 15:24:13 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:24:15 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:24:27 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:24:39 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:24:41 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 15:24:49 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:24:52 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:25:01 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 15:25:06 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:25:14 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:25:16 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:25:18 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:25:24 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 15:25:26 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 15:25:28 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:25:36 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:25:44 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:25:48 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:25:50 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:26:10 INFO - PROCESS | 7225 | Cycle 1(1): loaded http://localhost:58280/tests/dromaeo/cssquery-ext.html (next: http://localhost:58280/tests/dromaeo/cssquery-jquery.html) 15:26:11 INFO - PROCESS | 7225 | RSS: Main: 271183872 15:26:11 INFO - PROCESS | 7225 | 15:26:32 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:26:44 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:26:46 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:26:56 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:26:58 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:27:00 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:27:02 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 15:27:12 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:27:16 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:27:36 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 15:27:45 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:27:53 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:27:59 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:28:01 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:28:03 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 15:28:06 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:28:21 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:28:23 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:28:27 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:28:31 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:28:39 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:28:44 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 15:28:51 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:28:55 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:28:57 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:29:08 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:29:11 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:29:13 INFO - PROCESS | 7225 | Cycle 1(1): loaded http://localhost:58280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58280/tests/dromaeo/cssquery-mootools.html) 15:29:14 INFO - PROCESS | 7225 | RSS: Main: 222642176 15:29:14 INFO - PROCESS | 7225 | 15:29:22 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:29:23 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 2.0501 seconds. 15:29:29 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:29:33 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:29:35 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 15:29:40 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:29:41 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:29:45 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:29:47 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:29:49 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:29:54 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:30:01 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 15:30:08 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:30:16 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:30:17 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:30:21 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:30:31 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:30:33 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:30:37 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:30:41 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:30:53 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:31:00 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:31:02 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 15:31:14 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:31:18 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:31:34 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:31:36 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:31:38 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:31:47 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:31:58 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:32:14 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 15:32:16 INFO - PROCESS | 7225 | Cycle 1(1): loaded http://localhost:58280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58280/tests/dromaeo/cssquery-prototype.html) 15:32:17 INFO - PROCESS | 7225 | RSS: Main: 217767936 15:32:17 INFO - PROCESS | 7225 | 15:32:31 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:32:33 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:32:35 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:32:38 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:32:56 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:33:08 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:33:13 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:33:18 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:33:50 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:33:51 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 15:34:03 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:34:05 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:34:21 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 15:34:23 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:34:25 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:34:31 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:34:33 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.03343 seconds. 15:34:56 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:34:58 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:35:05 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:35:18 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 15:35:20 INFO - PROCESS | 7225 | Cycle 1(1): loaded http://localhost:58280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58280/tests/dromaeo/cssquery-yui.html) 15:35:21 INFO - PROCESS | 7225 | RSS: Main: 222179328 15:35:21 INFO - PROCESS | 7225 | 15:35:24 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 15:35:27 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:35:35 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:35:37 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:35:41 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:35:47 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:35:51 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:35:55 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:36:11 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:36:14 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:36:38 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:37:17 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:37:19 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:37:33 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:37:35 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:37:37 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:37:49 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:37:57 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:38:11 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:38:15 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 15:38:17 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.01314 seconds. 15:38:22 INFO - PROCESS | 7225 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 15:38:23 INFO - PROCESS | 7225 | Cycle 1(1): loaded http://localhost:58280/tests/dromaeo/cssquery-yui.html (next: http://localhost:58280/tests/dromaeo/cssquery-dojo.html) 15:38:24 INFO - PROCESS | 7225 | RSS: Main: 308609024 15:38:24 INFO - PROCESS | 7225 | 15:38:24 INFO - PROCESS | 7225 | __start_tp_report 15:38:24 INFO - PROCESS | 7225 | _x_x_mozilla_page_load 15:38:24 INFO - PROCESS | 7225 | _x_x_mozilla_page_load_details 15:38:24 INFO - PROCESS | 7225 | |i|pagename|runs| 15:38:24 INFO - PROCESS | 7225 | |0;dojo.html;2599.999000384852;2623.3703911061352;2650.1916915340826;2655.920322390328;2673.8529380884056;4640.466346370168;4665.671508557202;4701.741404222775;4740.478547359792;4767.070421267854;4684.086603112394;4702.271147972061;4721.315409265654;4738.9526104739025;4753.191957367248;4281.05816720321;4302.053548219403;4329.610335069843;4348.130373925212;4379.146066517031;4370.32259999699;4395.296752519597;4423.66822488313;4430.379746835446;4481.708688935209;4223.72545784523;4278.122984788111;4358.651307895392;4395.340698895159;4403.8238470461465;4269.466316710411;4290.292101803198;4294.914101717979;4296.3555466680245;4301.655867530621;4302.763348015857;4303.031817840961;4336.978315108436;4385.166818304512;4392.692511524195;4669.719816811003;4709.434867815849;4725.125851717443;4761.40482439695;4777;4898.755062246873;4955.603551715854;5051.545360917498;5053.519915608048;5056.696598204119;5025.346704928373;5034.09386310464;5043.470435604246;5053.166227572447;5080.491950804927;4727.409073865767;4728.125296820031;4786.16242157621;4786.688865223715;4788.186008378514;5177.974110129425;5186.844394668166;5188.403333616682;5188.688678679291;5258.474152584711;4805.639577031737;4917.5328343808;4920.483349248278;4925.5074492551175;4987.177115775857;5005.724685142352;5038.370203724535;5040.143175660147;5041.193409054534;5124.077666020152;4957.603391728653;5007;5007.349044624176;5013.924791128136;5070.923936140961;4832.685875418087;4999.625028122905;5013.573846223039;5020.974895125501;5071.8985620287385;4165.125323682;4208.789560522023;4218.303979843292;4220.07158425146;4230.323148296257;3691.446283057563;3695.9076023099637;3697.7411581189594;3739.5325584301963;3740.551133863954;4358;4360.7601581913295;4362.17118747437;4387.42963414754;4408.911821763547;4866.31871537985;4918.065567542156;4987.800487980513;5032.119379108642;5050.090983622983;4372.125574885035;4379.343098535245;4397.516273209944;4403.097365040223;4437.2234858899565;4534.727916325031;4539.0694907543875;4543.5002149763495;4550.544945505423;4598.26427771555;4713.033828065307;4761.071591039714;4778.808847646055;4788.281757736368;4814.542618451242;5077.31456253401;5161.122609156528;5167.99999999985;5197.66215196011;5224.085784987536;4857.101436234308;4864.075825593126;4865.343178670976;4874.147024270667;4929;4889.90220195587;4937.35814344133;5044.293798868088;5060.544550990428;5121.308623335802;5001.57486613642;5043.9243411348125;5045.873853153554;5072.5688316493515;5138.409082955508;4858.489858564941;4929.260610908393;4934.9753251233515;4941.901161976813;5006.424261210008;4782.856514304576;4920.1389756791705;4927.039227350776;4931.926021109614;4993.251012348177;4745.217039188633;4784.210605250095;4841.903161936812;4854.441739199896;4934.457209706858;4961.5286547777905;4968.527989840959;4973.602111831117;4977.153883839684;4997.5002499749735;4730.030343779448;4785.521447855187;4790.473047964791;4830.444498882533;4835.540623640749 15:38:24 INFO - PROCESS | 7225 | |1;ext.html;559.3036669346664;559.5859064292422;566.0320851344201;567.205911723587;570.7146426786607;27572.58641120382;37016;38405.807970960144;38457.23085538288;42608.360874586906;39191.21617567647;39621.60378396222;39661.8016909915;39693;39780.20439591214;7533.246675332451;7673.117591476968;7685.346745526609;7691.961540192291;7696.923030769704;7486.288802563775;7554.69781208751;7560.168381478033;7568.167501574822;7569.545827250355;7621.161672216052;7626.19924907885;7672.961635191817;7677.155512893577;7679.884801727951;7612.352949999256;7617.961910190441;7625.694972201105;7653.119891212498;7656.234376562299;7277.745278915266;7330.523515971445;7332.670029848617;7338.339549440576;7340.926590734078;35774.28451430957;36135.27729445396;36207.818960905024;36233;36241.818790905876;2705.3101459127993;2792.2600510864636;2833.929151771222;3094.6441159266747;3152.1646763607664;27489.72510274892;27950.16149515518;28070.859645701847;28444.573331400046;28540.714592854012;3180.9482036763948;3201.2477067889026;3205.951910721341;3224.0972527692047;3248.2366643838677;268214;269280;269960;271340;271404;52451;53098.73450632722;53115.46884531183;53141;53159.46840531545;31920.361592768015;33051.504227436606;33153.3369332612;35025;55270;12930.870691293156;13039.348032598333;13044.869551304555;13053.608391748263;13055.477780888848;9890.851637225447;9970.454579540183;9972.401655900525;9973.80052398948;9976.700698979042;17626.383076592258;17665.823341766674;17726;17754.644907101785;17779;6706.798796036127;6709.496787740939;6809.795706128824;6835.316468353224;6886.931130688629;721.7473884140509;811.3833486550265;824.6989848705299;851.4617090905459;860.7245681382022;26534.734652653227;27966;28017.159485215478;28017.299567510567;28385.432291354056;846.5881210006355;850.7617866997251;890.6303883888244;920.6777627830339;922.6263363337724;14198.361073751706;14426.927865360605;14444.711105778037;14461.927690361481;14510.564683059525;365617;368117;369422;369574;369915;48038.27942580864;48557.27164092541;48661.270080948816;48692.75653621709;48703.512964870606;1017.1201910347644;1064.4677661169414;1068.3376306689747;1102.415719668577;1132.1395739238073;26360.34099147537;28116.85941570279;29297.85351073231;31342.529862052543;31788.84105579457;45787.31319030151;46257.3061404086;46369.53630463787;46416.76791616155;46572.534274658174;320901;324023;324951;324951;324985;24659.260222193363;24749.381265467786;25074.37314067163;25104.246872593852;25134.371640709127;41127;41178;41256.58743412527;41359.793201033805;41486.58513414827;35046.12384690403;35048.299034019685;35050.474242885866;35427;35676.64323356837;34444.31111377809;34874.651253488155;35071.47392789059;35235.29529409346;35349;27774.722252777214;33890.49164262489;34972;34985.47521787226;35217.47173792446;30010.54984175283;32413;32485;32734.508982365758;32794.34411311808;22018.688037755805;22698.659520107543;22891.427714307276;23098.76901230966;23117.422064448525 15:38:24 INFO - PROCESS | 7225 | |2;jquery.html;342.640227760851;353.681686482166;355.5253736262091;356.20388431854815;356.2998707539685;12236.143469957107;14975.925120374397;15166.2416879156;15909.204539772994;17038.914805425986;16396.75404868928;16687.165641717897;16825.41111061113;16974.83025169751;17023.57441063974;4793.640476964224;4877.122118018755;4895.167821470359;4933.062718083553;5075.238714192864;3645.726570507209;3875.7286989910576;4846.151923413407;4918.286848406979;4919.311296418504;5709.800156994485;6019.3980601939675;6038.5471089668235;6045.335013148572;6048.939510604903;5682.147677848315;5853.32686741026;5903.321118071433;6007.489363404094;6014.8496287592725;5162.767675454614;5185.792568297264;5191.169412893956;5195.558377537876;5196.298499702568;19596.21615135393;19872.10575524105;19889.403317900487;19993;20651.380458586114;1793.8116497767808;1817.9909100454413;1914.349121298765;2051.7845626209332;2290.415943934286;12618.936905315506;13320;13371.465141394332;13742.519011834638;13941.930290348584;1700.2858799304322;1728.5592173995676;1753.4038426934903;1758.0945812906364;1777.3512667876262;69193;70605.64697176532;72391.63804180999;72767.90848137335;73426.63286683532;8304.418690691715;8943.971443283976;9020.909790902138;9177.678781242625;9317.767055823522;11813.940930295294;11927.284362938251;11972.700682483008;11974;11978.281303121841;6525.543211975211;6758.357955994271;6838.863222735517;6944.097267355211;6973.755918542928;6252.406021427957;6330.177076979963;6354.3010268870385;6469.320721324193;6525.706343214567;3535.151563624763;3692.2061756722433;3773.6792372648633;3777.036855601859;4046.656034237064;1779.9288028478716;1792.9759704251487;1843.0508288231572;1846.4183782108594;1877.981220187808;773.7833406646035;774.2760518914832;791.2285521616378;848.9830203395898;891.4562117108559;15378.769318460232;16302.510924672279;16428.1785910704;16798.580035498966;17675.292988280326;789.0649580247425;807.9959600201961;818.1981657975099;824.7855557555079;844.2907957315762;6894.793156205322;8939.910600894038;10026.949865250774;10133.442660653734;10499.527521261562;421578;424398;426548;429668;431063;48562.27156592654;80744.59627701824;82682;83649.58175209085;84777.57611211904;912.539167720297;912.9589168487435;942.5569982108398;943.9622415103456;945.976350591227;15853.52439426819;15948.760768588712;16393.262303196625;16485.17574121313;16829;39213.41179882247;40271.79864100661;40570;40664.59335406726;40809.79595102005;46508.069838602365;52418.73790631023;53332;54469.45530544802;54790;5049.671771334852;6547.476201903741;6833.111695479556;7450.36671882896;7754;9708.951455242961;11663.88336116662;12144.210626309261;12272.693182670504;12362.876371236172;9442.905570944202;9638.277129215197;9962.651307204213;10191.03185197405;10787.514561844579;9698.757531061498;10423.270371073952;10448.492361833258;10519.421431821323;10995.285306455055;9268;9763.755906102404;10214.336068155546;10636.202284828514;11119.777604448027;6939.757108501179;7017.333353331425;7200.783976480714;7215.546714117016;7267.709291628275;9842.65550705722;10155.238357123393;10304.587816487257;10409.583616655249;10479.476026198507 15:38:24 INFO - PROCESS | 7225 | |3;mootools.html;1349.3320806200932;1390.8539603341649;1432.9426822927082;1676.5221911755136;1705.7356109802986;6882.346177113179;7156.033935418717;7496.92503074969;8147.959260203698;8162.755117346482;7417.295356941096;7732.342750866169;7735.535867847933;7952.920470795305;7969.083555391132;2160.3194993577054;2190.76996915324;2192.3861318830673;2194.2100843696257;2194.846360754748;1855.5175654329903;1917.1277068933603;2094.0576740466777;2119.8622089564205;2148.1085349579907;2154.526004279056;2217.2904670505445;2217.9556408871813;2221.9888900555475;2230.029936977412;2161.2651698422455;2164.2316977473083;2165.7184566006476;2172.923947661824;2182.72715910512;2087.0295312679523;2131.893405329727;2142.410837019832;2143.485563464773;2145.774693657175;7874.094479134916;8054.234847689519;8129.59352032402;8136.186381361876;8136.267735903738;4337.327714204303;4428.136513379892;4465.330200469955;4465.732056076646;4468.597826195625;2813.732695393934;2899.420115976813;2954.6749857515656;2971.747401470856;2979.791414600979;926.7080869526078;928.308410234373;942.8821397325335;951.4434056077204;957.698325027614;10560.630377936812;10573.841392379121;10581.576736930514;10585.57657693699;10590.205734569938;4082.2447847148374;4115.300398932189;4214.873553793391;4291.034517233597;4304.5910638489295;9140.954295228481;9210.95394523023;9227.400218985744;9236.168744812865;9244.121808428188;4509.503954564995;4510.300903359985;4511.210538155809;4511.390962220124;4514.164879497303;3969.384745364473;4120.052387950788;4120.649744771728;4122.361034039729;4122.896927576775;3357.546731191258;3403.931921361559;3489.4940233665975;3493.2489514754448;3533.6643018913164;940.0834186667944;950.1068995144542;1028.9176865850714;1037.4968140451874;1047.219821233171;620.2866703291161;628.2586547873553;646.8156575375996;658.4534836086037;730.4996077686801;4867.683600565952;5004.649674522821;5034.874128146826;5050.267711181931;5173.146430838945;428.48957425076856;432.7814453700861;435.0298833601087;455.11480171066955;594.0613830148355;3358.261182539837;3372.207531230159;3452.5166476693284;3527.4356103023392;3623.166671665532;9527.618895244252;9895;9960.352577082467;9970.202383809277;9978.401295922124;9920.652777152765;9940.652077177409;9945.900540994642;9946.651867184613;9952.253580981456;6997.1253593300835;7167.498275120795;7444.441666874898;7518.436117291225;7521.360684341783;5215.582753379645;5303.443138470405;5336;5370.382406023357;5378.97310513445;8713.738587842376;8731.519766412654;8771.473711577326;8870.778230544289;8957.14907083826;8788.868166977372;8917.10828917103;8924.241439477717;8997.595108220015;9000.774980625536;6623.105880706235;6657.068010478633;6773.051772751917;6783.864322713617;6789.830254243683;7597.6201189939175;7639.159692433939;7687.807804804925;7712.652930618248;7719.035120609924;6544.6727663615675;6587.835304117435;6720.395164435225;6739.393454589111;6861.965690171517;6396.264429590657;6459.9031014533875;6492.935070649422;6520.6413647247955;6612.305707900794;6139.171211886338;6237.12680224778;6248.250209974832;6260.248770147611;6303.6532990685955;4437.5784300491405;4556.653323538314;4580.977095114503;4655.976720116378;4686.461056978491;4194.077302993336;4314.61168494837;4353.673474489363;4379.343098535245;4385.780710964503 15:38:24 INFO - PROCESS | 7225 | |4;prototype.html;285.23699104894405;297.6606668398027;298.1027108403705;314.2034941423492;316.8083309597693;4780.976095119524;4829.492903245161;4832.106060378833;4856.39295088114;4857.48996355382;4769.403824521934;4769.8807529811775;4803.831865884695;4806.9038619227595;4832.154372984731;3834.213986132836;3850.9229815403673;3851.133494963625;3857.1321452673205;3862.6909847212296;3692.5384326958997;3847.2882516734353;3872.1287710265105;3887.591802860701;3888.300105980922;3778.641029102245;3800.9429858552007;3828.3300422426105;3829.5978922213185;3830.1190726133004;3777.9811100944594;3791.1469919267943;3807.390817469219;3810.256999884996;3814.6376094271;3132.9530057049164;3239.7894136881264;3268.6404495505476;3389.9830500847584;3390.948805870188;4653.674242803005;4677.859664210079;4680.625549956029;4682.6019788318035;4684.367610372624;4284.850030248957;4286.678499112579;4299.903524601238;4304.289792184286;4304.978475107636;4677.859664210079;4753.47711751707;5007.173816320303;5029.547340739351;5047.873803154914;3671.559412870446;3672;3676.8161591920298;3682.0794801299676;3685.1524149445777;5220.399654039758;5221.754645502432;5223.764930578133;5242.9213561796605;5254.4220135785035;3633.9999999999472;3725.4784330193793;3771.867984620525;3780.0343821212814;3780.716446266541;3790.090378309186;3798.7150963677836;3806.238752249506;3809.42858571211;3833.9488373720483;4219.725717828332;4276.850310239127;4277.700560960765;4283.593058659422;4286.571342865688;4983.426905905795;4989.900201996013;4997.800087996512;5002.399712034507;5074.188129899198;1256.3039896140137;1310.2793463594985;1314.9737005259842;1320.630223537411;1325.3240847168013;856.1652388920877;868.2966796894535;875.6103533927342;878.5826732302105;881.6253092435818;4007.4189242560246;4024.577419370924;4028.637422631977;4069.8400955727475;4082.2856000199845;4605.618314505635;4616.538346165356;4620.19146649335;4639.726533998317;4648.814047438133;4057.4928133983253;4061.5126184858004;4068.2677118119022;4083.571225021389;4095.344744840811;2291.255342013852;2423.8303318767516;2436.5370579590176;2447.3881529617597;2496.901363400098;4377.562243775597;4698.8355407560575;4798.568128868418;4824.855254342376;4940.802367905244;5314.601404894648;5329.228801023523;5346.064438723208;5359.705216213146;5365.275687782178;4909.214525675946;4921.778519966539;4922.286268490977;4976.303317535476;4982.501749824988;5037.765747391913;5171.3535808024;5180.171172612364;5198.922016169685;5208.635395522429;5046;5095.464976177448;5107.41264754558;5108.131617624938;5113.181890897438;4760.333553302471;4924.433690125681;4930.334404855299;4947.79822978771;4954.9752251238515;3756.0985363513105;3764.1342491226624;3771.396576547739;3781.489498917611;3800.35393983018;5195.558377537952;5198;5211.400688920823;5217.035053359398;5250.343707036621;5042.949570504248;5060.089183946925;5066.138756411346;5086.135356989246;5095.210242412432;4803.423589169322;5053.159598704937;5054.21659642756;5070.949290507047;5080.136376815876;4989.750512474289;5001.224810154432;5003.874903127451;5005.1241032819835;5018.322526458881;4899.926501102415;5074.746262686777;5080.7205603692155;5090.49095090488;5118.641695081308;4966.900661986812;4977.601791856715;4978.954419571931;4986;4989.879301297007 15:38:24 INFO - PROCESS | 7225 | |5;yui.html;535.2934126952423;539.3931826694968;541.0639593503239;559.197551513578;596.1356033751063;11697.532098716052;13062.738745225113;13078.476860925564;13139.145955512886;13246.40391182398;11853.585124520643;12006.819897701542;12048.39758012098;12101.757964840719;12151.149419540636;602.9758809647609;739.0355585960319;757.7537300377372;758.8671982403085;759.1270039454644;624.3350831364583;736.0688728758125;758.9241075892394;762.8550575390658;767.0258771360369;801.5992003998001;803.2449497472386;804.2399932064202;808.4583329169441;809.0291650019971;634.8476365672205;797.0873350014231;798.876174193001;799.8440304140695;799.9600019998977;478.4187212536784;561.8370672504968;573.9167820666022;580.962237454564;582.1558739827225;12577.74844503114;12634.936825315906;12639.747205055848;12642;12643.367831608382;12044.638660840188;12224.572139975058;12340.38298085101;12973.675658108528;13422;10315.587376504931;10402.167826573856;10411.167106631452;10429.947850260776;10478.842817357745;4144.254034273829;4177.603127702863;4189.7695626740515;4232.9153416931795;4244;63665.36334636641;63919.680401597696;65401.67299163521;65662.34337656658;67106;10408.323458975143;10540.52567634443;10604.469776511143;10607.522661480252;10755.462226888782;19421.805781942283;19661.705074423895;19706.802931970782;19714;19724.40826775199;6661.700223489955;6700.83247918806;6771.52599318043;6880.346367095118;6997.93002069983;3638.490611314418;3653.963460365362;3663.4871118043493;3664.13892735207;3750.8124593770203;2855.072101566999;2925.283305590144;2928.736413722733;2930.721581449759;2939.0448104365737;464.54706661005247;479.86803629002304;482.7417331727509;484.48644436897;488.57982135363557;444.22261043174444;469.51170782385935;525.7082319312818;529.0635575032248;533.237470417307;7016.899703287709;7020.754273600502;7076.044733960849;7143.107111611049;7292.0396228667205;428.2847644433803;435.77958883848385;472.832144588673;484.9078675051729;507.652258203132;3341.599008119041;3457.896263112111;3507.105688049582;3540.858365665396;3576.910577235538;56665;71747;102775.48612256891;105781.47109264405;105921;49738.75130624396;75583;86240;88925;90085.54957225303;78234.52358334976;80500.19499804926;82118;83860.58069709613;85889.57055214683;7422.183559808309;8318.584070796556;8462.11147829469;8527.829443411221;8630.482171069756;40762.79618601888;41755.58244417517;42003;44453.55546444623;46451.303230450896;61482.69258653678;62651;63045.3464436323;63984.3601563997;65517.87919234957;10528.210384221062;10549.156067514426;10635.8936410638;10744.677659670222;10865.945670271914;11999.280043197436;12510.562130325396;12735.04487163485;12851;12922.483100675867;11505.424728763695;11535.480903359537;11775;11869.287842729464;12081.456334465145;11155.665330040112;11591.420428978687;11622.07023438106;11972.911512606934;11983.580574679845;10856.185786065918;10927.56289748433;11025.117990560897;11849.585264515701;11858.110641701734;9871.605135794487;9997.150242229201;10348.379097254188;10397.376157430579;10478.109360704426;8730.345224108345;8834.77913052153;9707.417554946725;9902.405855648683;9961.153301969414 15:38:24 INFO - PROCESS | 7225 | __end_tp_report 15:38:24 INFO - PROCESS | 7225 | __start_cc_report 15:38:24 INFO - PROCESS | 7225 | _x_x_mozilla_cycle_collect,983 15:38:24 INFO - PROCESS | 7225 | __end_cc_report 15:38:24 INFO - PROCESS | 7225 | __startTimestamp1474065504220__endTimestamp 15:38:24 INFO - PROCESS | 7225 | 15:38:24 INFO - PROCESS | 7225 | ------- Summary: start ------- 15:38:24 INFO - PROCESS | 7225 | Number of tests: 6 15:38:24 INFO - PROCESS | 7225 | 15:38:24 INFO - PROCESS | 7225 | [#0] dojo.html Cycles:165 Average:4667.68 Median:4788.19 stddev:491.48 (10.3%) stddev-sans-first:465.46 15:38:24 INFO - PROCESS | 7225 | Values: 2600.0 2623.4 2650.2 2655.9 2673.9 4640.5 4665.7 4701.7 4740.5 4767.1 4684.1 4702.3 4721.3 4739.0 4753.2 4281.1 4302.1 4329.6 4348.1 4379.1 4370.3 4395.3 4423.7 4430.4 4481.7 4223.7 4278.1 4358.7 4395.3 4403.8 4269.5 4290.3 4294.9 4296.4 4301.7 4302.8 4303.0 4337.0 4385.2 4392.7 4669.7 4709.4 4725.1 4761.4 4777.0 4898.8 4955.6 5051.5 5053.5 5056.7 5025.3 5034.1 5043.5 5053.2 5080.5 4727.4 4728.1 4786.2 4786.7 4788.2 5178.0 5186.8 5188.4 5188.7 5258.5 4805.6 4917.5 4920.5 4925.5 4987.2 5005.7 5038.4 5040.1 5041.2 5124.1 4957.6 5007.0 5007.3 5013.9 5070.9 4832.7 4999.6 5013.6 5021.0 5071.9 4165.1 4208.8 4218.3 4220.1 4230.3 3691.4 3695.9 3697.7 3739.5 3740.6 4358.0 4360.8 4362.2 4387.4 4408.9 4866.3 4918.1 4987.8 5032.1 5050.1 4372.1 4379.3 4397.5 4403.1 4437.2 4534.7 4539.1 4543.5 4550.5 4598.3 4713.0 4761.1 4778.8 4788.3 4814.5 5077.3 5161.1 5168.0 5197.7 5224.1 4857.1 4864.1 4865.3 4874.1 4929.0 4889.9 4937.4 5044.3 5060.5 5121.3 5001.6 5043.9 5045.9 5072.6 5138.4 4858.5 4929.3 4935.0 4941.9 5006.4 4782.9 4920.1 4927.0 4931.9 4993.3 4745.2 4784.2 4841.9 4854.4 4934.5 4961.5 4968.5 4973.6 4977.2 4997.5 4730.0 4785.5 4790.5 4830.4 4835.5 15:38:24 INFO - PROCESS | 7225 | 15:38:24 INFO - PROCESS | 7225 | [#1] ext.html Cycles:180 Average:46657.53 Median:35954.78 stddev:85125.18 (236.8%) stddev-sans-first:85293.62 15:38:24 INFO - PROCESS | 7225 | Values: 559.3 559.6 566.0 567.2 570.7 27572.6 37016.0 38405.8 38457.2 42608.4 39191.2 39621.6 39661.8 39693.0 39780.2 7533.2 7673.1 7685.3 7692.0 7696.9 7486.3 7554.7 7560.2 7568.2 7569.5 7621.2 7626.2 7673.0 7677.2 7679.9 7612.4 7618.0 7625.7 7653.1 7656.2 7277.7 7330.5 7332.7 7338.3 7340.9 35774.3 36135.3 36207.8 36233.0 36241.8 2705.3 2792.3 2833.9 3094.6 3152.2 27489.7 27950.2 28070.9 28444.6 28540.7 3180.9 3201.2 3206.0 3224.1 3248.2 268214.0 269280.0 269960.0 271340.0 271404.0 52451.0 53098.7 53115.5 53141.0 53159.5 31920.4 33051.5 33153.3 35025.0 55270.0 12930.9 13039.3 13044.9 13053.6 13055.5 9890.9 9970.5 9972.4 9973.8 9976.7 17626.4 17665.8 17726.0 17754.6 17779.0 6706.8 6709.5 6809.8 6835.3 6886.9 721.7 811.4 824.7 851.5 860.7 26534.7 27966.0 28017.2 28017.3 28385.4 846.6 850.8 890.6 920.7 922.6 14198.4 14426.9 14444.7 14461.9 14510.6 365617.0 368117.0 369422.0 369574.0 369915.0 48038.3 48557.3 48661.3 48692.8 48703.5 1017.1 1064.5 1068.3 1102.4 1132.1 26360.3 28116.9 29297.9 31342.5 31788.8 45787.3 46257.3 46369.5 46416.8 46572.5 320901.0 324023.0 324951.0 324951.0 324985.0 24659.3 24749.4 25074.4 25104.2 25134.4 41127.0 41178.0 41256.6 41359.8 41486.6 35046.1 35048.3 35050.5 35427.0 35676.6 34444.3 34874.7 35071.5 35235.3 35349.0 27774.7 33890.5 34972.0 34985.5 35217.5 30010.5 32413.0 32485.0 32734.5 32794.3 22018.7 22698.7 22891.4 23098.8 23117.4 15:38:24 INFO - PROCESS | 7225 | 15:38:24 INFO - PROCESS | 7225 | [#2] jquery.html Cycles:180 Average:25373.49 Median:430365.50 stddev:70354.91 (16.3%) stddev-sans-first:70527.17 15:38:24 INFO - PROCESS | 7225 | Values: 342.6 353.7 355.5 356.2 356.3 12236.1 14975.9 15166.2 15909.2 17038.9 16396.8 16687.2 16825.4 16974.8 17023.6 4793.6 4877.1 4895.2 4933.1 5075.2 3645.7 3875.7 4846.2 4918.3 4919.3 5709.8 6019.4 6038.5 6045.3 6048.9 5682.1 5853.3 5903.3 6007.5 6014.8 5162.8 5185.8 5191.2 5195.6 5196.3 19596.2 19872.1 19889.4 19993.0 20651.4 1793.8 1818.0 1914.3 2051.8 2290.4 12618.9 13320.0 13371.5 13742.5 13941.9 1700.3 1728.6 1753.4 1758.1 1777.4 69193.0 70605.6 72391.6 72767.9 73426.6 8304.4 8944.0 9020.9 9177.7 9317.8 11813.9 11927.3 11972.7 11974.0 11978.3 6525.5 6758.4 6838.9 6944.1 6973.8 6252.4 6330.2 6354.3 6469.3 6525.7 3535.2 3692.2 3773.7 3777.0 4046.7 1779.9 1793.0 1843.1 1846.4 1878.0 773.8 774.3 791.2 849.0 891.5 15378.8 16302.5 16428.2 16798.6 17675.3 789.1 808.0 818.2 824.8 844.3 6894.8 8939.9 10026.9 10133.4 10499.5 421578.0 424398.0 426548.0 429668.0 431063.0 48562.3 80744.6 82682.0 83649.6 84777.6 912.5 913.0 942.6 944.0 946.0 15853.5 15948.8 16393.3 16485.2 16829.0 39213.4 40271.8 40570.0 40664.6 40809.8 46508.1 52418.7 53332.0 54469.5 54790.0 5049.7 6547.5 6833.1 7450.4 7754.0 9709.0 11663.9 12144.2 12272.7 12362.9 9442.9 9638.3 9962.7 10191.0 10787.5 9698.8 10423.3 10448.5 10519.4 10995.3 9268.0 9763.8 10214.3 10636.2 11119.8 6939.8 7017.3 7200.8 7215.5 7267.7 9842.7 10155.2 10304.6 10409.6 10479.5 15:38:24 INFO - PROCESS | 7225 | 15:38:24 INFO - PROCESS | 7225 | [#3] mootools.html Cycles:180 Average:5097.79 Median:5111.71 stddev:2940.26 (57.5%) stddev-sans-first:2935.02 15:38:24 INFO - PROCESS | 7225 | Values: 1349.3 1390.9 1432.9 1676.5 1705.7 6882.3 7156.0 7496.9 8148.0 8162.8 7417.3 7732.3 7735.5 7952.9 7969.1 2160.3 2190.8 2192.4 2194.2 2194.8 1855.5 1917.1 2094.1 2119.9 2148.1 2154.5 2217.3 2218.0 2222.0 2230.0 2161.3 2164.2 2165.7 2172.9 2182.7 2087.0 2131.9 2142.4 2143.5 2145.8 7874.1 8054.2 8129.6 8136.2 8136.3 4337.3 4428.1 4465.3 4465.7 4468.6 2813.7 2899.4 2954.7 2971.7 2979.8 926.7 928.3 942.9 951.4 957.7 10560.6 10573.8 10581.6 10585.6 10590.2 4082.2 4115.3 4214.9 4291.0 4304.6 9141.0 9211.0 9227.4 9236.2 9244.1 4509.5 4510.3 4511.2 4511.4 4514.2 3969.4 4120.1 4120.6 4122.4 4122.9 3357.5 3403.9 3489.5 3493.2 3533.7 940.1 950.1 1028.9 1037.5 1047.2 620.3 628.3 646.8 658.5 730.5 4867.7 5004.6 5034.9 5050.3 5173.1 428.5 432.8 435.0 455.1 594.1 3358.3 3372.2 3452.5 3527.4 3623.2 9527.6 9895.0 9960.4 9970.2 9978.4 9920.7 9940.7 9945.9 9946.7 9952.3 6997.1 7167.5 7444.4 7518.4 7521.4 5215.6 5303.4 5336.0 5370.4 5379.0 8713.7 8731.5 8771.5 8870.8 8957.1 8788.9 8917.1 8924.2 8997.6 9000.8 6623.1 6657.1 6773.1 6783.9 6789.8 7597.6 7639.2 7687.8 7712.7 7719.0 6544.7 6587.8 6720.4 6739.4 6862.0 6396.3 6459.9 6492.9 6520.6 6612.3 6139.2 6237.1 6248.3 6260.2 6303.7 4437.6 4556.7 4581.0 4656.0 4686.5 4194.1 4314.6 4353.7 4379.3 4385.8 15:38:24 INFO - PROCESS | 7225 | 15:38:24 INFO - PROCESS | 7225 | [#4] prototype.html Cycles:180 Average:4159.56 Median:4683.48 stddev:1208.79 (25.8%) stddev-sans-first:1176.68 15:38:24 INFO - PROCESS | 7225 | Values: 285.2 297.7 298.1 314.2 316.8 4781.0 4829.5 4832.1 4856.4 4857.5 4769.4 4769.9 4803.8 4806.9 4832.2 3834.2 3850.9 3851.1 3857.1 3862.7 3692.5 3847.3 3872.1 3887.6 3888.3 3778.6 3800.9 3828.3 3829.6 3830.1 3778.0 3791.1 3807.4 3810.3 3814.6 3133.0 3239.8 3268.6 3390.0 3390.9 4653.7 4677.9 4680.6 4682.6 4684.4 4284.9 4286.7 4299.9 4304.3 4305.0 4677.9 4753.5 5007.2 5029.5 5047.9 3671.6 3672.0 3676.8 3682.1 3685.2 5220.4 5221.8 5223.8 5242.9 5254.4 3634.0 3725.5 3771.9 3780.0 3780.7 3790.1 3798.7 3806.2 3809.4 3833.9 4219.7 4276.9 4277.7 4283.6 4286.6 4983.4 4989.9 4997.8 5002.4 5074.2 1256.3 1310.3 1315.0 1320.6 1325.3 856.2 868.3 875.6 878.6 881.6 4007.4 4024.6 4028.6 4069.8 4082.3 4605.6 4616.5 4620.2 4639.7 4648.8 4057.5 4061.5 4068.3 4083.6 4095.3 2291.3 2423.8 2436.5 2447.4 2496.9 4377.6 4698.8 4798.6 4824.9 4940.8 5314.6 5329.2 5346.1 5359.7 5365.3 4909.2 4921.8 4922.3 4976.3 4982.5 5037.8 5171.4 5180.2 5198.9 5208.6 5046.0 5095.5 5107.4 5108.1 5113.2 4760.3 4924.4 4930.3 4947.8 4955.0 3756.1 3764.1 3771.4 3781.5 3800.4 5195.6 5198.0 5211.4 5217.0 5250.3 5042.9 5060.1 5066.1 5086.1 5095.2 4803.4 5053.2 5054.2 5070.9 5080.1 4989.8 5001.2 5003.9 5005.1 5018.3 4899.9 5074.7 5080.7 5090.5 5118.6 4966.9 4977.6 4979.0 4986.0 4989.9 15:38:24 INFO - PROCESS | 7225 | 15:38:24 INFO - PROCESS | 7225 | [#5] yui.html Cycles:180 Average:17522.27 Median:440.00 stddev:25201.90 (5727.7%) stddev-sans-first:25240.32 15:38:24 INFO - PROCESS | 7225 | Values: 535.3 539.4 541.1 559.2 596.1 11697.5 13062.7 13078.5 13139.1 13246.4 11853.6 12006.8 12048.4 12101.8 12151.1 603.0 739.0 757.8 758.9 759.1 624.3 736.1 758.9 762.9 767.0 801.6 803.2 804.2 808.5 809.0 634.8 797.1 798.9 799.8 800.0 478.4 561.8 573.9 581.0 582.2 12577.7 12634.9 12639.7 12642.0 12643.4 12044.6 12224.6 12340.4 12973.7 13422.0 10315.6 10402.2 10411.2 10429.9 10478.8 4144.3 4177.6 4189.8 4232.9 4244.0 63665.4 63919.7 65401.7 65662.3 67106.0 10408.3 10540.5 10604.5 10607.5 10755.5 19421.8 19661.7 19706.8 19714.0 19724.4 6661.7 6700.8 6771.5 6880.3 6997.9 3638.5 3654.0 3663.5 3664.1 3750.8 2855.1 2925.3 2928.7 2930.7 2939.0 464.5 479.9 482.7 484.5 488.6 444.2 469.5 525.7 529.1 533.2 7016.9 7020.8 7076.0 7143.1 7292.0 428.3 435.8 472.8 484.9 507.7 3341.6 3457.9 3507.1 3540.9 3576.9 56665.0 71747.0 102775.5 105781.5 105921.0 49738.8 75583.0 86240.0 88925.0 90085.5 78234.5 80500.2 82118.0 83860.6 85889.6 7422.2 8318.6 8462.1 8527.8 8630.5 40762.8 41755.6 42003.0 44453.6 46451.3 61482.7 62651.0 63045.3 63984.4 65517.9 10528.2 10549.2 10635.9 10744.7 10865.9 11999.3 12510.6 12735.0 12851.0 12922.5 11505.4 11535.5 11775.0 11869.3 12081.5 11155.7 11591.4 11622.1 11972.9 11983.6 10856.2 10927.6 11025.1 11849.6 11858.1 9871.6 9997.2 10348.4 10397.4 10478.1 8730.3 8834.8 9707.4 9902.4 9961.2 15:38:24 INFO - PROCESS | 7225 | -------- Summary: end -------- 15:38:24 INFO - PROCESS | 7225 | 15:38:25 INFO - TEST-INFO | 7225: exit 0 15:38:25 INFO - TEST-OK | dromaeo_css | took 1121136ms 15:38:25 INFO - TEST-START | kraken 15:38:25 INFO - operating with platform_type : linux_ 15:38:25 INFO - Initialising browser for kraken test... 15:38:25 INFO - TEST-INFO | started process 7532 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp09lsPN/profile http://localhost:58280/getInfo.html) 15:38:29 INFO - PROCESS | 7532 | __metrics Screen width/height:1600/1200 15:38:29 INFO - PROCESS | 7532 | colorDepth:24 15:38:29 INFO - PROCESS | 7532 | Browser inner width/height: 1024/697 15:38:29 INFO - PROCESS | 7532 | __metrics 15:38:30 INFO - PROCESS | 7532 | 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" 15:38:30 INFO - PROCESS | 7532 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:38:30 INFO - PROCESS | 7532 | console.error: PushService: 15:38:30 INFO - PROCESS | 7532 | stateChangeProcessEnqueue: Error transitioning state 15:38:30 INFO - PROCESS | 7532 | UnknownError 15:38:31 INFO - TEST-INFO | 7532: exit 0 15:38:31 INFO - Browser initialized. 15:38:31 INFO - Running cycle 1/1 for kraken test... 15:38:31 INFO - TEST-INFO | started process 7610 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp09lsPN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:38:43 INFO - PROCESS | 7610 | RSS: Main: 174202880 15:38:43 INFO - PROCESS | 7610 | 15:38:46 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:58280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58280/tests/kraken/driver.html?testName=audio-beat-detection) 15:38:47 INFO - PROCESS | 7610 | RSS: Main: 233631744 15:38:47 INFO - PROCESS | 7610 | 15:38:50 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:58280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58280/tests/kraken/driver.html?testName=audio-dft) 15:38:50 INFO - PROCESS | 7610 | RSS: Main: 217157632 15:38:50 INFO - PROCESS | 7610 | 15:38:53 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:58280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58280/tests/kraken/driver.html?testName=audio-fft) 15:38:54 INFO - PROCESS | 7610 | RSS: Main: 300052480 15:38:54 INFO - PROCESS | 7610 | 15:38:56 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:58280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58280/tests/kraken/driver.html?testName=audio-oscillator) 15:38:57 INFO - PROCESS | 7610 | RSS: Main: 209154048 15:38:57 INFO - PROCESS | 7610 | 15:38:59 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:58280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:39:00 INFO - PROCESS | 7610 | RSS: Main: 213139456 15:39:00 INFO - PROCESS | 7610 | 15:39:03 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:58280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58280/tests/kraken/driver.html?testName=imaging-darkroom) 15:39:04 INFO - PROCESS | 7610 | RSS: Main: 249802752 15:39:04 INFO - PROCESS | 7610 | 15:39:07 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:58280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58280/tests/kraken/driver.html?testName=imaging-desaturate) 15:39:08 INFO - PROCESS | 7610 | RSS: Main: 243499008 15:39:08 INFO - PROCESS | 7610 | 15:39:11 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:58280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58280/tests/kraken/driver.html?testName=json-parse-financial) 15:39:11 INFO - PROCESS | 7610 | RSS: Main: 247271424 15:39:11 INFO - PROCESS | 7610 | 15:39:13 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:58280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:39:13 INFO - PROCESS | 7610 | RSS: Main: 210198528 15:39:13 INFO - PROCESS | 7610 | 15:39:15 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:58280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58280/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:39:16 INFO - PROCESS | 7610 | RSS: Main: 217247744 15:39:16 INFO - PROCESS | 7610 | 15:39:18 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:58280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:39:19 INFO - PROCESS | 7610 | RSS: Main: 221237248 15:39:19 INFO - PROCESS | 7610 | 15:39:22 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:58280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:39:22 INFO - PROCESS | 7610 | RSS: Main: 222216192 15:39:22 INFO - PROCESS | 7610 | 15:39:25 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:58280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:39:26 INFO - PROCESS | 7610 | RSS: Main: 220655616 15:39:26 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:58280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58280/tests/kraken/driver.html?testName=ai-astar) 15:39:28 INFO - PROCESS | 7610 | RSS: Main: 271847424 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | __start_tp_report 15:39:28 INFO - PROCESS | 7610 | _x_x_mozilla_page_load 15:39:28 INFO - PROCESS | 7610 | _x_x_mozilla_page_load_details 15:39:28 INFO - PROCESS | 7610 | |i|pagename|runs| 15:39:28 INFO - PROCESS | 7610 | |0;ai-astar;113;116;117;116;114;117;115;115;116;115 15:39:28 INFO - PROCESS | 7610 | |1;audio-beat-detection;160;128;132;164;126;130;133;124;161;141 15:39:28 INFO - PROCESS | 7610 | |2;audio-dft;162;164;165;163;162;162;163;161;161;162 15:39:28 INFO - PROCESS | 7610 | |3;audio-fft;78;77;78;114;78;77;78;78;102;75 15:39:28 INFO - PROCESS | 7610 | |4;audio-oscillator;85;84;120;83;85;83;105;83;83;112 15:39:28 INFO - PROCESS | 7610 | |5;imaging-gaussian-blur;117;118;118;117;117;119;121;117;118;117 15:39:28 INFO - PROCESS | 7610 | |6;imaging-darkroom;148;148;148;147;147;147;147;147;148;148 15:39:28 INFO - PROCESS | 7610 | |7;imaging-desaturate;95;96;95;95;94;94;98;95;95;95 15:39:28 INFO - PROCESS | 7610 | |8;json-parse-financial;64;63;64;63;64;65;64;64;64;63 15:39:28 INFO - PROCESS | 7610 | |9;json-stringify-tinderbox;66;67;66;67;66;67;68;67;66;67 15:39:28 INFO - PROCESS | 7610 | |10;stanford-crypto-aes;83;82;83;83;83;83;85;82;91;86 15:39:28 INFO - PROCESS | 7610 | |11;stanford-crypto-ccm;136;133;132;131;133;132;134;133;185;134 15:39:28 INFO - PROCESS | 7610 | |12;stanford-crypto-pbkdf2;175;173;173;177;174;181;174;176;173;174 15:39:28 INFO - PROCESS | 7610 | |13;stanford-crypto-sha256-iterative;71;77;70;70;70;73;73;71;70;70 15:39:28 INFO - PROCESS | 7610 | __end_tp_report 15:39:28 INFO - PROCESS | 7610 | __start_cc_report 15:39:28 INFO - PROCESS | 7610 | _x_x_mozilla_cycle_collect,1962 15:39:28 INFO - PROCESS | 7610 | __end_cc_report 15:39:28 INFO - PROCESS | 7610 | __startTimestamp1474065568427__endTimestamp 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | ------- Summary: start ------- 15:39:28 INFO - PROCESS | 7610 | Number of tests: 14 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | [#0] ai-astar Cycles:10 Average:115.40 Median:116.00 stddev:1.26 (1.1%) stddev-sans-first:1.00 15:39:28 INFO - PROCESS | 7610 | Values: 113.0 116.0 117.0 116.0 114.0 117.0 115.0 115.0 116.0 115.0 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:137.00 stddev:15.73 (11.5%) stddev-sans-first:14.91 15:39:28 INFO - PROCESS | 7610 | Values: 160.0 128.0 132.0 164.0 126.0 130.0 133.0 124.0 161.0 141.0 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.50 stddev:1.27 (0.8%) stddev-sans-first:1.33 15:39:28 INFO - PROCESS | 7610 | Values: 162.0 164.0 165.0 163.0 162.0 162.0 163.0 161.0 161.0 162.0 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | [#3] audio-fft Cycles:10 Average:83.50 Median:78.00 stddev:13.25 (17.0%) stddev-sans-first:13.91 15:39:28 INFO - PROCESS | 7610 | Values: 78.0 77.0 78.0 114.0 78.0 77.0 78.0 78.0 102.0 75.0 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | [#4] audio-oscillator Cycles:10 Average:92.30 Median:83.00 stddev:14.29 (17.2%) stddev-sans-first:14.91 15:39:28 INFO - PROCESS | 7610 | Values: 85.0 84.0 120.0 83.0 85.0 83.0 105.0 83.0 83.0 112.0 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:1.29 (1.1%) stddev-sans-first:1.32 15:39:28 INFO - PROCESS | 7610 | Values: 117.0 118.0 118.0 117.0 117.0 119.0 121.0 117.0 118.0 117.0 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 15:39:28 INFO - PROCESS | 7610 | Values: 148.0 148.0 148.0 147.0 147.0 147.0 147.0 147.0 148.0 148.0 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:1.14 (1.2%) stddev-sans-first:1.20 15:39:28 INFO - PROCESS | 7610 | Values: 95.0 96.0 95.0 95.0 94.0 94.0 98.0 95.0 95.0 95.0 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:64.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 15:39:28 INFO - PROCESS | 7610 | Values: 64.0 63.0 64.0 63.0 64.0 65.0 64.0 64.0 64.0 63.0 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 15:39:28 INFO - PROCESS | 7610 | Values: 66.0 67.0 66.0 67.0 66.0 67.0 68.0 67.0 66.0 67.0 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:83.00 stddev:2.73 (3.3%) stddev-sans-first:2.86 15:39:28 INFO - PROCESS | 7610 | Values: 83.0 82.0 83.0 83.0 83.0 83.0 85.0 82.0 91.0 86.0 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | [#11] stanford-crypto-ccm Cycles:10 Average:138.30 Median:133.50 stddev:16.47 (12.3%) stddev-sans-first:17.44 15:39:28 INFO - PROCESS | 7610 | Values: 136.0 133.0 132.0 131.0 133.0 132.0 134.0 133.0 185.0 134.0 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.00 Median:174.50 stddev:2.49 (1.4%) stddev-sans-first:2.65 15:39:28 INFO - PROCESS | 7610 | Values: 175.0 173.0 173.0 177.0 174.0 181.0 174.0 176.0 173.0 174.0 15:39:28 INFO - PROCESS | 7610 | 15:39:28 INFO - PROCESS | 7610 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:71.00 stddev:2.27 (3.2%) stddev-sans-first:2.40 15:39:28 INFO - PROCESS | 7610 | Values: 71.0 77.0 70.0 70.0 70.0 73.0 73.0 71.0 70.0 70.0 15:39:28 INFO - PROCESS | 7610 | -------- Summary: end -------- 15:39:28 INFO - PROCESS | 7610 | 15:39:29 INFO - TEST-INFO | 7610: exit 0 15:39:29 INFO - TEST-OK | kraken | took 64438ms 15:39:29 INFO - SUITE-END | took 1185s 15:39:30 INFO - Completed test suite (00:19:46) 15:39:30 INFO - javascript benchmark 15:39:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2599.999000384852, 2623.3703911061352, 2650.1916915340826, 2655.920322390328, 2673.8529380884056, 4640.466346370168, 4665.671508557202, 4701.741404222775, 4740.478547359792, 4767.070421267854, 4684.086603112394, 4702.271147972061, 4721.315409265654, 4738.9526104739025, 4753.191957367248, 4281.05816720321, 4302.053548219403, 4329.610335069843, 4348.130373925212, 4379.146066517031, 4370.32259999699, 4395.296752519597, 4423.66822488313, 4430.379746835446, 4481.708688935209, 4223.72545784523, 4278.122984788111, 4358.651307895392, 4395.340698895159, 4403.8238470461465, 4269.466316710411, 4290.292101803198, 4294.914101717979, 4296.3555466680245, 4301.655867530621, 4302.763348015857, 4303.031817840961, 4336.978315108436, 4385.166818304512, 4392.692511524195, 4669.719816811003, 4709.434867815849, 4725.125851717443, 4761.40482439695, 4777.0, 4898.755062246873, 4955.603551715854, 5051.545360917498, 5053.519915608048, 5056.696598204119, 5025.346704928373, 5034.09386310464, 5043.470435604246, 5053.166227572447, 5080.491950804927, 4727.409073865767, 4728.125296820031, 4786.16242157621, 4786.688865223715, 4788.186008378514, 5177.974110129425, 5186.844394668166, 5188.403333616682, 5188.688678679291, 5258.474152584711, 4805.639577031737, 4917.5328343808, 4920.483349248278, 4925.5074492551175, 4987.177115775857, 5005.724685142352, 5038.370203724535, 5040.143175660147, 5041.193409054534, 5124.077666020152, 4957.603391728653, 5007.0, 5007.349044624176, 5013.924791128136, 5070.923936140961, 4832.685875418087, 4999.625028122905, 5013.573846223039, 5020.974895125501, 5071.8985620287385, 4165.125323682, 4208.789560522023, 4218.303979843292, 4220.07158425146, 4230.323148296257, 3691.446283057563, 3695.9076023099637, 3697.7411581189594, 3739.5325584301963, 3740.551133863954, 4358.0, 4360.7601581913295, 4362.17118747437, 4387.42963414754, 4408.911821763547, 4866.31871537985, 4918.065567542156, 4987.800487980513, 5032.119379108642, 5050.090983622983, 4372.125574885035, 4379.343098535245, 4397.516273209944, 4403.097365040223, 4437.2234858899565, 4534.727916325031, 4539.0694907543875, 4543.5002149763495, 4550.544945505423, 4598.26427771555, 4713.033828065307, 4761.071591039714, 4778.808847646055, 4788.281757736368, 4814.542618451242, 5077.31456253401, 5161.122609156528, 5167.99999999985, 5197.66215196011, 5224.085784987536, 4857.101436234308, 4864.075825593126, 4865.343178670976, 4874.147024270667, 4929.0, 4889.90220195587, 4937.35814344133, 5044.293798868088, 5060.544550990428, 5121.308623335802, 5001.57486613642, 5043.9243411348125, 5045.873853153554, 5072.5688316493515, 5138.409082955508, 4858.489858564941, 4929.260610908393, 4934.9753251233515, 4941.901161976813, 5006.424261210008, 4782.856514304576, 4920.1389756791705, 4927.039227350776, 4931.926021109614, 4993.251012348177, 4745.217039188633, 4784.210605250095, 4841.903161936812, 4854.441739199896, 4934.457209706858, 4961.5286547777905, 4968.527989840959, 4973.602111831117, 4977.153883839684, 4997.5002499749735, 4730.030343779448, 4785.521447855187, 4790.473047964791, 4830.444498882533, 4835.540623640749], "value": 4636.16427631312, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.3036669346664, 559.5859064292422, 566.0320851344201, 567.205911723587, 570.7146426786607, 27572.58641120382, 37016.0, 38405.807970960144, 38457.23085538288, 42608.360874586906, 39191.21617567647, 39621.60378396222, 39661.8016909915, 39693.0, 39780.20439591214, 7533.246675332451, 7673.117591476968, 7685.346745526609, 7691.961540192291, 7696.923030769704, 7486.288802563775, 7554.69781208751, 7560.168381478033, 7568.167501574822, 7569.545827250355, 7621.161672216052, 7626.19924907885, 7672.961635191817, 7677.155512893577, 7679.884801727951, 7612.352949999256, 7617.961910190441, 7625.694972201105, 7653.119891212498, 7656.234376562299, 7277.745278915266, 7330.523515971445, 7332.670029848617, 7338.339549440576, 7340.926590734078, 35774.28451430957, 36135.27729445396, 36207.818960905024, 36233.0, 36241.818790905876, 2705.3101459127993, 2792.2600510864636, 2833.929151771222, 3094.6441159266747, 3152.1646763607664, 27489.72510274892, 27950.16149515518, 28070.859645701847, 28444.573331400046, 28540.714592854012, 3180.9482036763948, 3201.2477067889026, 3205.951910721341, 3224.0972527692047, 3248.2366643838677, 268214.0, 269280.0, 269960.0, 271340.0, 271404.0, 52451.0, 53098.73450632722, 53115.46884531183, 53141.0, 53159.46840531545, 31920.361592768015, 33051.504227436606, 33153.3369332612, 35025.0, 55270.0, 12930.870691293156, 13039.348032598333, 13044.869551304555, 13053.608391748263, 13055.477780888848, 9890.851637225447, 9970.454579540183, 9972.401655900525, 9973.80052398948, 9976.700698979042, 17626.383076592258, 17665.823341766674, 17726.0, 17754.644907101785, 17779.0, 6706.798796036127, 6709.496787740939, 6809.795706128824, 6835.316468353224, 6886.931130688629, 721.7473884140509, 811.3833486550265, 824.6989848705299, 851.4617090905459, 860.7245681382022, 26534.734652653227, 27966.0, 28017.159485215478, 28017.299567510567, 28385.432291354056, 846.5881210006355, 850.7617866997251, 890.6303883888244, 920.6777627830339, 922.6263363337724, 14198.361073751706, 14426.927865360605, 14444.711105778037, 14461.927690361481, 14510.564683059525, 365617.0, 368117.0, 369422.0, 369574.0, 369915.0, 48038.27942580864, 48557.27164092541, 48661.270080948816, 48692.75653621709, 48703.512964870606, 1017.1201910347644, 1064.4677661169414, 1068.3376306689747, 1102.415719668577, 1132.1395739238073, 26360.34099147537, 28116.85941570279, 29297.85351073231, 31342.529862052543, 31788.84105579457, 45787.31319030151, 46257.3061404086, 46369.53630463787, 46416.76791616155, 46572.534274658174, 320901.0, 324023.0, 324951.0, 324951.0, 324985.0, 24659.260222193363, 24749.381265467786, 25074.37314067163, 25104.246872593852, 25134.371640709127, 41127.0, 41178.0, 41256.58743412527, 41359.793201033805, 41486.58513414827, 35046.12384690403, 35048.299034019685, 35050.474242885866, 35427.0, 35676.64323356837, 34444.31111377809, 34874.651253488155, 35071.47392789059, 35235.29529409346, 35349.0, 27774.722252777214, 33890.49164262489, 34972.0, 34985.47521787226, 35217.47173792446, 30010.54984175283, 32413.0, 32485.0, 32734.508982365758, 32794.34411311808, 22018.688037755805, 22698.659520107543, 22891.427714307276, 23098.76901230966, 23117.422064448525], "value": 16781.12752513601, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.640227760851, 353.681686482166, 355.5253736262091, 356.20388431854815, 356.2998707539685, 12236.143469957107, 14975.925120374397, 15166.2416879156, 15909.204539772994, 17038.914805425986, 16396.75404868928, 16687.165641717897, 16825.41111061113, 16974.83025169751, 17023.57441063974, 4793.640476964224, 4877.122118018755, 4895.167821470359, 4933.062718083553, 5075.238714192864, 3645.726570507209, 3875.7286989910576, 4846.151923413407, 4918.286848406979, 4919.311296418504, 5709.800156994485, 6019.3980601939675, 6038.5471089668235, 6045.335013148572, 6048.939510604903, 5682.147677848315, 5853.32686741026, 5903.321118071433, 6007.489363404094, 6014.8496287592725, 5162.767675454614, 5185.792568297264, 5191.169412893956, 5195.558377537876, 5196.298499702568, 19596.21615135393, 19872.10575524105, 19889.403317900487, 19993.0, 20651.380458586114, 1793.8116497767808, 1817.9909100454413, 1914.349121298765, 2051.7845626209332, 2290.415943934286, 12618.936905315506, 13320.0, 13371.465141394332, 13742.519011834638, 13941.930290348584, 1700.2858799304322, 1728.5592173995676, 1753.4038426934903, 1758.0945812906364, 1777.3512667876262, 69193.0, 70605.64697176532, 72391.63804180999, 72767.90848137335, 73426.63286683532, 8304.418690691715, 8943.971443283976, 9020.909790902138, 9177.678781242625, 9317.767055823522, 11813.940930295294, 11927.284362938251, 11972.700682483008, 11974.0, 11978.281303121841, 6525.543211975211, 6758.357955994271, 6838.863222735517, 6944.097267355211, 6973.755918542928, 6252.406021427957, 6330.177076979963, 6354.3010268870385, 6469.320721324193, 6525.706343214567, 3535.151563624763, 3692.2061756722433, 3773.6792372648633, 3777.036855601859, 4046.656034237064, 1779.9288028478716, 1792.9759704251487, 1843.0508288231572, 1846.4183782108594, 1877.981220187808, 773.7833406646035, 774.2760518914832, 791.2285521616378, 848.9830203395898, 891.4562117108559, 15378.769318460232, 16302.510924672279, 16428.1785910704, 16798.580035498966, 17675.292988280326, 789.0649580247425, 807.9959600201961, 818.1981657975099, 824.7855557555079, 844.2907957315762, 6894.793156205322, 8939.910600894038, 10026.949865250774, 10133.442660653734, 10499.527521261562, 421578.0, 424398.0, 426548.0, 429668.0, 431063.0, 48562.27156592654, 80744.59627701824, 82682.0, 83649.58175209085, 84777.57611211904, 912.539167720297, 912.9589168487435, 942.5569982108398, 943.9622415103456, 945.976350591227, 15853.52439426819, 15948.760768588712, 16393.262303196625, 16485.17574121313, 16829.0, 39213.41179882247, 40271.79864100661, 40570.0, 40664.59335406726, 40809.79595102005, 46508.069838602365, 52418.73790631023, 53332.0, 54469.45530544802, 54790.0, 5049.671771334852, 6547.476201903741, 6833.111695479556, 7450.36671882896, 7754.0, 9708.951455242961, 11663.88336116662, 12144.210626309261, 12272.693182670504, 12362.876371236172, 9442.905570944202, 9638.277129215197, 9962.651307204213, 10191.03185197405, 10787.514561844579, 9698.757531061498, 10423.270371073952, 10448.492361833258, 10519.421431821323, 10995.285306455055, 9268.0, 9763.755906102404, 10214.336068155546, 10636.202284828514, 11119.777604448027, 6939.757108501179, 7017.333353331425, 7200.783976480714, 7215.546714117016, 7267.709291628275, 9842.65550705722, 10155.238357123393, 10304.587816487257, 10409.583616655249, 10479.476026198507], "value": 8091.267784333348, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1349.3320806200932, 1390.8539603341649, 1432.9426822927082, 1676.5221911755136, 1705.7356109802986, 6882.346177113179, 7156.033935418717, 7496.92503074969, 8147.959260203698, 8162.755117346482, 7417.295356941096, 7732.342750866169, 7735.535867847933, 7952.920470795305, 7969.083555391132, 2160.3194993577054, 2190.76996915324, 2192.3861318830673, 2194.2100843696257, 2194.846360754748, 1855.5175654329903, 1917.1277068933603, 2094.0576740466777, 2119.8622089564205, 2148.1085349579907, 2154.526004279056, 2217.2904670505445, 2217.9556408871813, 2221.9888900555475, 2230.029936977412, 2161.2651698422455, 2164.2316977473083, 2165.7184566006476, 2172.923947661824, 2182.72715910512, 2087.0295312679523, 2131.893405329727, 2142.410837019832, 2143.485563464773, 2145.774693657175, 7874.094479134916, 8054.234847689519, 8129.59352032402, 8136.186381361876, 8136.267735903738, 4337.327714204303, 4428.136513379892, 4465.330200469955, 4465.732056076646, 4468.597826195625, 2813.732695393934, 2899.420115976813, 2954.6749857515656, 2971.747401470856, 2979.791414600979, 926.7080869526078, 928.308410234373, 942.8821397325335, 951.4434056077204, 957.698325027614, 10560.630377936812, 10573.841392379121, 10581.576736930514, 10585.57657693699, 10590.205734569938, 4082.2447847148374, 4115.300398932189, 4214.873553793391, 4291.034517233597, 4304.5910638489295, 9140.954295228481, 9210.95394523023, 9227.400218985744, 9236.168744812865, 9244.121808428188, 4509.503954564995, 4510.300903359985, 4511.210538155809, 4511.390962220124, 4514.164879497303, 3969.384745364473, 4120.052387950788, 4120.649744771728, 4122.361034039729, 4122.896927576775, 3357.546731191258, 3403.931921361559, 3489.4940233665975, 3493.2489514754448, 3533.6643018913164, 940.0834186667944, 950.1068995144542, 1028.9176865850714, 1037.4968140451874, 1047.219821233171, 620.2866703291161, 628.2586547873553, 646.8156575375996, 658.4534836086037, 730.4996077686801, 4867.683600565952, 5004.649674522821, 5034.874128146826, 5050.267711181931, 5173.146430838945, 428.48957425076856, 432.7814453700861, 435.0298833601087, 455.11480171066955, 594.0613830148355, 3358.261182539837, 3372.207531230159, 3452.5166476693284, 3527.4356103023392, 3623.166671665532, 9527.618895244252, 9895.0, 9960.352577082467, 9970.202383809277, 9978.401295922124, 9920.652777152765, 9940.652077177409, 9945.900540994642, 9946.651867184613, 9952.253580981456, 6997.1253593300835, 7167.498275120795, 7444.441666874898, 7518.436117291225, 7521.360684341783, 5215.582753379645, 5303.443138470405, 5336.0, 5370.382406023357, 5378.97310513445, 8713.738587842376, 8731.519766412654, 8771.473711577326, 8870.778230544289, 8957.14907083826, 8788.868166977372, 8917.10828917103, 8924.241439477717, 8997.595108220015, 9000.774980625536, 6623.105880706235, 6657.068010478633, 6773.051772751917, 6783.864322713617, 6789.830254243683, 7597.6201189939175, 7639.159692433939, 7687.807804804925, 7712.652930618248, 7719.035120609924, 6544.6727663615675, 6587.835304117435, 6720.395164435225, 6739.393454589111, 6861.965690171517, 6396.264429590657, 6459.9031014533875, 6492.935070649422, 6520.6413647247955, 6612.305707900794, 6139.171211886338, 6237.12680224778, 6248.250209974832, 6260.248770147611, 6303.6532990685955, 4437.5784300491405, 4556.653323538314, 4580.977095114503, 4655.976720116378, 4686.461056978491, 4194.077302993336, 4314.61168494837, 4353.673474489363, 4379.343098535245, 4385.780710964503], "value": 3998.1516989972943, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.23699104894405, 297.6606668398027, 298.1027108403705, 314.2034941423492, 316.8083309597693, 4780.976095119524, 4829.492903245161, 4832.106060378833, 4856.39295088114, 4857.48996355382, 4769.403824521934, 4769.8807529811775, 4803.831865884695, 4806.9038619227595, 4832.154372984731, 3834.213986132836, 3850.9229815403673, 3851.133494963625, 3857.1321452673205, 3862.6909847212296, 3692.5384326958997, 3847.2882516734353, 3872.1287710265105, 3887.591802860701, 3888.300105980922, 3778.641029102245, 3800.9429858552007, 3828.3300422426105, 3829.5978922213185, 3830.1190726133004, 3777.9811100944594, 3791.1469919267943, 3807.390817469219, 3810.256999884996, 3814.6376094271, 3132.9530057049164, 3239.7894136881264, 3268.6404495505476, 3389.9830500847584, 3390.948805870188, 4653.674242803005, 4677.859664210079, 4680.625549956029, 4682.6019788318035, 4684.367610372624, 4284.850030248957, 4286.678499112579, 4299.903524601238, 4304.289792184286, 4304.978475107636, 4677.859664210079, 4753.47711751707, 5007.173816320303, 5029.547340739351, 5047.873803154914, 3671.559412870446, 3672.0, 3676.8161591920298, 3682.0794801299676, 3685.1524149445777, 5220.399654039758, 5221.754645502432, 5223.764930578133, 5242.9213561796605, 5254.4220135785035, 3633.9999999999472, 3725.4784330193793, 3771.867984620525, 3780.0343821212814, 3780.716446266541, 3790.090378309186, 3798.7150963677836, 3806.238752249506, 3809.42858571211, 3833.9488373720483, 4219.725717828332, 4276.850310239127, 4277.700560960765, 4283.593058659422, 4286.571342865688, 4983.426905905795, 4989.900201996013, 4997.800087996512, 5002.399712034507, 5074.188129899198, 1256.3039896140137, 1310.2793463594985, 1314.9737005259842, 1320.630223537411, 1325.3240847168013, 856.1652388920877, 868.2966796894535, 875.6103533927342, 878.5826732302105, 881.6253092435818, 4007.4189242560246, 4024.577419370924, 4028.637422631977, 4069.8400955727475, 4082.2856000199845, 4605.618314505635, 4616.538346165356, 4620.19146649335, 4639.726533998317, 4648.814047438133, 4057.4928133983253, 4061.5126184858004, 4068.2677118119022, 4083.571225021389, 4095.344744840811, 2291.255342013852, 2423.8303318767516, 2436.5370579590176, 2447.3881529617597, 2496.901363400098, 4377.562243775597, 4698.8355407560575, 4798.568128868418, 4824.855254342376, 4940.802367905244, 5314.601404894648, 5329.228801023523, 5346.064438723208, 5359.705216213146, 5365.275687782178, 4909.214525675946, 4921.778519966539, 4922.286268490977, 4976.303317535476, 4982.501749824988, 5037.765747391913, 5171.3535808024, 5180.171172612364, 5198.922016169685, 5208.635395522429, 5046.0, 5095.464976177448, 5107.41264754558, 5108.131617624938, 5113.181890897438, 4760.333553302471, 4924.433690125681, 4930.334404855299, 4947.79822978771, 4954.9752251238515, 3756.0985363513105, 3764.1342491226624, 3771.396576547739, 3781.489498917611, 3800.35393983018, 5195.558377537952, 5198.0, 5211.400688920823, 5217.035053359398, 5250.343707036621, 5042.949570504248, 5060.089183946925, 5066.138756411346, 5086.135356989246, 5095.210242412432, 4803.423589169322, 5053.159598704937, 5054.21659642756, 5070.949290507047, 5080.136376815876, 4989.750512474289, 5001.224810154432, 5003.874903127451, 5005.1241032819835, 5018.322526458881, 4899.926501102415, 5074.746262686777, 5080.7205603692155, 5090.49095090488, 5118.641695081308, 4966.900661986812, 4977.601791856715, 4978.954419571931, 4986.0, 4989.879301297007], "value": 3778.3806735675757, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [535.2934126952423, 539.3931826694968, 541.0639593503239, 559.197551513578, 596.1356033751063, 11697.532098716052, 13062.738745225113, 13078.476860925564, 13139.145955512886, 13246.40391182398, 11853.585124520643, 12006.819897701542, 12048.39758012098, 12101.757964840719, 12151.149419540636, 602.9758809647609, 739.0355585960319, 757.7537300377372, 758.8671982403085, 759.1270039454644, 624.3350831364583, 736.0688728758125, 758.9241075892394, 762.8550575390658, 767.0258771360369, 801.5992003998001, 803.2449497472386, 804.2399932064202, 808.4583329169441, 809.0291650019971, 634.8476365672205, 797.0873350014231, 798.876174193001, 799.8440304140695, 799.9600019998977, 478.4187212536784, 561.8370672504968, 573.9167820666022, 580.962237454564, 582.1558739827225, 12577.74844503114, 12634.936825315906, 12639.747205055848, 12642.0, 12643.367831608382, 12044.638660840188, 12224.572139975058, 12340.38298085101, 12973.675658108528, 13422.0, 10315.587376504931, 10402.167826573856, 10411.167106631452, 10429.947850260776, 10478.842817357745, 4144.254034273829, 4177.603127702863, 4189.7695626740515, 4232.9153416931795, 4244.0, 63665.36334636641, 63919.680401597696, 65401.67299163521, 65662.34337656658, 67106.0, 10408.323458975143, 10540.52567634443, 10604.469776511143, 10607.522661480252, 10755.462226888782, 19421.805781942283, 19661.705074423895, 19706.802931970782, 19714.0, 19724.40826775199, 6661.700223489955, 6700.83247918806, 6771.52599318043, 6880.346367095118, 6997.93002069983, 3638.490611314418, 3653.963460365362, 3663.4871118043493, 3664.13892735207, 3750.8124593770203, 2855.072101566999, 2925.283305590144, 2928.736413722733, 2930.721581449759, 2939.0448104365737, 464.54706661005247, 479.86803629002304, 482.7417331727509, 484.48644436897, 488.57982135363557, 444.22261043174444, 469.51170782385935, 525.7082319312818, 529.0635575032248, 533.237470417307, 7016.899703287709, 7020.754273600502, 7076.044733960849, 7143.107111611049, 7292.0396228667205, 428.2847644433803, 435.77958883848385, 472.832144588673, 484.9078675051729, 507.652258203132, 3341.599008119041, 3457.896263112111, 3507.105688049582, 3540.858365665396, 3576.910577235538, 56665.0, 71747.0, 102775.48612256891, 105781.47109264405, 105921.0, 49738.75130624396, 75583.0, 86240.0, 88925.0, 90085.54957225303, 78234.52358334976, 80500.19499804926, 82118.0, 83860.58069709613, 85889.57055214683, 7422.183559808309, 8318.584070796556, 8462.11147829469, 8527.829443411221, 8630.482171069756, 40762.79618601888, 41755.58244417517, 42003.0, 44453.55546444623, 46451.303230450896, 61482.69258653678, 62651.0, 63045.3464436323, 63984.3601563997, 65517.87919234957, 10528.210384221062, 10549.156067514426, 10635.8936410638, 10744.677659670222, 10865.945670271914, 11999.280043197436, 12510.562130325396, 12735.04487163485, 12851.0, 12922.483100675867, 11505.424728763695, 11535.480903359537, 11775.0, 11869.287842729464, 12081.456334465145, 11155.665330040112, 11591.420428978687, 11622.07023438106, 11972.911512606934, 11983.580574679845, 10856.185786065918, 10927.56289748433, 11025.117990560897, 11849.585264515701, 11858.110641701734, 9871.605135794487, 9997.150242229201, 10348.379097254188, 10397.376157430579, 10478.109360704426, 8730.345224108345, 8834.77913052153, 9707.417554946725, 9902.405855648683, 9961.153301969414], "value": 6344.235463215214, "unit": "score"}], "name": "dromaeo_css", "value": 6262.74881931146, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 117.0, 116.0, 114.0, 117.0, 115.0, 115.0, 116.0, 115.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 128.0, 132.0, 164.0, 126.0, 130.0, 133.0, 124.0, 161.0, 141.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 165.0, 163.0, 162.0, 162.0, 163.0, 161.0, 161.0, 162.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 78.0, 114.0, 78.0, 77.0, 78.0, 78.0, 102.0, 75.0], "value": 83.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 120.0, 83.0, 85.0, 83.0, 105.0, 83.0, 83.0, 112.0], "value": 92.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 117.0, 117.0, 119.0, 121.0, 117.0, 118.0, 117.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 148.0, 148.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 94.0, 94.0, 98.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 63.0, 64.0, 65.0, 64.0, 64.0, 64.0, 63.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 66.0, 67.0, 68.0, 67.0, 66.0, 67.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 83.0, 83.0, 83.0, 85.0, 82.0, 91.0, 86.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 133.0, 132.0, 131.0, 133.0, 132.0, 134.0, 133.0, 185.0, 134.0], "value": 138.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 173.0, 173.0, 177.0, 174.0, 181.0, 174.0, 176.0, 173.0, 174.0], "value": 175.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 77.0, 70.0, 70.0, 70.0, 73.0, 73.0, 71.0, 70.0, 70.0], "value": 71.5, "unit": "score"}], "name": "kraken", "value": 1553.6, "alertThreshold": 2.0}]} 15:39:30 INFO - Return code: 0 15:39:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:39:30 INFO - # TBPL SUCCESS # 15:39:30 INFO - Running post-action listener: _resource_record_post_action 15:39:30 INFO - [mozharness: 2016-09-16 22:39:30.251846Z] Finished run-tests step (success) 15:39:30 INFO - Running post-run listener: _resource_record_post_run 15:39:30 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 406908928; Read time: 16; Write time: 317964 15:39:30 INFO - TinderboxPrint: CPU usage
13.1% 15:39:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 15:39:30 INFO - TinderboxPrint: I/O write bytes / time
406,908,928 / 317,964 15:39:30 INFO - TinderboxPrint: CPU idle
8,331.3 (86.9%) 15:39:30 INFO - TinderboxPrint: CPU user
1,205.0 (12.6%) 15:39:30 INFO - TinderboxPrint: Swap in / out
0 / 0 15:39:30 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 115752960; Read time: 0; Write time: 68460 15:39:30 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 291155968; Read time: 0; Write time: 249504 15:39:30 INFO - Running post-run listener: _upload_blobber_files 15:39:30 INFO - Blob upload gear active. 15:39:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:39:30 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 15:39:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:39:31 INFO - (blobuploader) - INFO - Open directory for files ... 15:39:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:39:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:39:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:32 INFO - (blobuploader) - INFO - Done attempting. 15:39:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:39:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:39:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:35 INFO - (blobuploader) - INFO - Done attempting. 15:39:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:39:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:39:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:36 INFO - (blobuploader) - INFO - Done attempting. 15:39:36 INFO - (blobuploader) - INFO - Iteration through files over. 15:39:36 INFO - Return code: 0 15:39:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:39:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:39:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bbe0f8f7651cd66c85fc63f7cc35e7bc7baa79179b4c529d18ae86870a1a9e9e7a5b42cc56300cd66010e19dd8dbcf2487345344746aa8e631a18bd699f0ccab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4a26f2223f9bb1cccf32f20201c91731493a2fd3badb81d46b2e79e6965d88263676288e7d057e05aa67ba1f62651f7989c49f418b60c66b2cd412382e6d31e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8d6ffce17c7491b5ce0795da5730e5ba948c3cd85c691d84d69d1c73ee945cfac182b383f2a49bc2cbf84f8f475783e93dfd11c2d09b3fbba46cd7b6a057949"} 15:39:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:39:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:39:36 INFO - Contents: 15:39:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bbe0f8f7651cd66c85fc63f7cc35e7bc7baa79179b4c529d18ae86870a1a9e9e7a5b42cc56300cd66010e19dd8dbcf2487345344746aa8e631a18bd699f0ccab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4a26f2223f9bb1cccf32f20201c91731493a2fd3badb81d46b2e79e6965d88263676288e7d057e05aa67ba1f62651f7989c49f418b60c66b2cd412382e6d31e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8d6ffce17c7491b5ce0795da5730e5ba948c3cd85c691d84d69d1c73ee945cfac182b383f2a49bc2cbf84f8f475783e93dfd11c2d09b3fbba46cd7b6a057949"} 15:39:36 INFO - Running post-run listener: copy_logs_to_upload_dir 15:39:36 INFO - Copying logs to upload dir... 15:39:36 INFO - mkdir: /builds/slave/test/build/upload/logs 15:39:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.601697 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-09-16 15:39:34.480863) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 15:39:34.484198) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bbe0f8f7651cd66c85fc63f7cc35e7bc7baa79179b4c529d18ae86870a1a9e9e7a5b42cc56300cd66010e19dd8dbcf2487345344746aa8e631a18bd699f0ccab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4a26f2223f9bb1cccf32f20201c91731493a2fd3badb81d46b2e79e6965d88263676288e7d057e05aa67ba1f62651f7989c49f418b60c66b2cd412382e6d31e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8d6ffce17c7491b5ce0795da5730e5ba948c3cd85c691d84d69d1c73ee945cfac182b383f2a49bc2cbf84f8f475783e93dfd11c2d09b3fbba46cd7b6a057949"} build_url:https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://queue.taskcluster.net/v1/task/IRBxSzi8QNOUOzz1yHmhIw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bbe0f8f7651cd66c85fc63f7cc35e7bc7baa79179b4c529d18ae86870a1a9e9e7a5b42cc56300cd66010e19dd8dbcf2487345344746aa8e631a18bd699f0ccab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4a26f2223f9bb1cccf32f20201c91731493a2fd3badb81d46b2e79e6965d88263676288e7d057e05aa67ba1f62651f7989c49f418b60c66b2cd412382e6d31e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8d6ffce17c7491b5ce0795da5730e5ba948c3cd85c691d84d69d1c73ee945cfac182b383f2a49bc2cbf84f8f475783e93dfd11c2d09b3fbba46cd7b6a057949"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 15:39:34.514244) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:39:34.515041) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1474063759.743668-590341077 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004483 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:39:34.598751) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 15:39:34.599508) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 15:39:34.600279) ========= ========= Total master_lag: 0.20 =========