builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-009 starttime: 1474359441.82 results: success (0) buildid: 20160920011712 builduid: ce3921cabfad475695eb4dde93b04cbd revision: c429089a8e66cff46a0fba433acee27f08057034 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 01:17:21.821298) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 01:17:21.821831) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 01:17:21.822105) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 01:17:21.863238) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:17:21.863497) ========= 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=17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 01:17:22-- 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% 43.0M=0s 2016-09-20 01:17:22 (43.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.086111 ========= master_lag: 0.27 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:17:22.220367) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:17:22.220774) ========= 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=17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015939 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:17:22.291850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 01:17:22.292165) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c429089a8e66cff46a0fba433acee27f08057034 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c429089a8e66cff46a0fba433acee27f08057034 --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=17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 01:17:22,538 truncating revision to first 12 chars 2016-09-20 01:17:22,538 Setting DEBUG logging. 2016-09-20 01:17:22,538 attempt 1/10 2016-09-20 01:17:22,538 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c429089a8e66?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 01:17:23,943 unpacking tar archive at: mozilla-aurora-c429089a8e66/testing/mozharness/ program finished with exit code 0 elapsedTime=1.780667 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 01:17:24.098740) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 01:17:24.099122) ========= script_repo_revision: c429089a8e66cff46a0fba433acee27f08057034 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 01:17:24.099499) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 01:17:24.099809) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 01:17:24.109273) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-09-20 01:17:24.109564) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:17:24 INFO - MultiFileLogger online at 20160920 01:17:24 in /builds/slave/test 01:17:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 01:17:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:17:24 INFO - {'append_to_log': False, 01:17:24 INFO - 'base_work_dir': '/builds/slave/test', 01:17:24 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:17:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:17:24 INFO - 'branch': 'Mozilla-Aurora', 01:17:24 INFO - 'buildbot_json_path': 'buildprops.json', 01:17:24 INFO - 'config_files': ('talos/linux_config.py',), 01:17:24 INFO - 'default_actions': ('clobber', 01:17:24 INFO - 'read-buildbot-config', 01:17:24 INFO - 'download-and-extract', 01:17:24 INFO - 'populate-webroot', 01:17:24 INFO - 'create-virtualenv', 01:17:24 INFO - 'install', 01:17:24 INFO - 'run-tests'), 01:17:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:17:24 INFO - 'download_minidump_stackwalk': True, 01:17:24 INFO - 'download_symbols': 'ondemand', 01:17:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:17:24 INFO - 'tooltool.py': '/tools/tooltool.py', 01:17:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:17:24 INFO - '/tools/misc-python/virtualenv.py')}, 01:17:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:17:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:17:24 INFO - 'installer_path': 'installer.exe', 01:17:24 INFO - 'log_level': 'info', 01:17:24 INFO - 'log_name': 'talos', 01:17:24 INFO - 'log_to_console': True, 01:17:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:17:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:17:24 INFO - 'opt_config_files': (), 01:17:24 INFO - 'pip_index': False, 01:17:24 INFO - 'sps_profile': False, 01:17:24 INFO - 'sps_profile_interval': 0, 01:17:24 INFO - 'suite': 'dromaeojs', 01:17:24 INFO - 'system_bits': '32', 01:17:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:17:24 INFO - 'title': 'talos-linux64-ix-009', 01:17:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:17:24 INFO - 'use_talos_json': True, 01:17:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:17:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:17:24 INFO - 'work_dir': 'build'} 01:17:24 INFO - [mozharness: 2016-09-20 08:17:24.439204Z] Running clobber step. 01:17:24 INFO - Running pre-action listener: _resource_record_pre_action 01:17:24 INFO - Running main action method: clobber 01:17:24 INFO - rmtree: /builds/slave/test/build 01:17:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:17:24 INFO - Running post-action listener: _resource_record_post_action 01:17:24 INFO - [mozharness: 2016-09-20 08:17:24.894408Z] Finished clobber step (success) 01:17:24 INFO - [mozharness: 2016-09-20 08:17:24.894510Z] Running read-buildbot-config step. 01:17:24 INFO - Running pre-action listener: _resource_record_pre_action 01:17:24 INFO - Running main action method: read_buildbot_config 01:17:24 INFO - Using buildbot properties: 01:17:24 INFO - { 01:17:24 INFO - "project": "", 01:17:24 INFO - "product": "firefox", 01:17:24 INFO - "script_repo_revision": "production", 01:17:24 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 01:17:24 INFO - "repository": "", 01:17:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 01:17:24 INFO - "buildid": "20160920011712", 01:17:24 INFO - "stage_platform": "linux64-pgo", 01:17:24 INFO - "basedir": "/builds/slave/test", 01:17:24 INFO - "buildnumber": 765, 01:17:24 INFO - "platform": "ubuntu64_hw", 01:17:24 INFO - "mozci_request": { 01:17:24 INFO - "priority": -1, 01:17:24 INFO - "type": "trigger_all_talos_jobs", 01:17:24 INFO - "times": 6 01:17:24 INFO - }, 01:17:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:17:24 INFO - "slavebuilddir": "test", 01:17:24 INFO - "branch": "mozilla-aurora", 01:17:24 INFO - "revision": "c429089a8e66cff46a0fba433acee27f08057034", 01:17:24 INFO - "slavename": "talos-linux64-ix-009", 01:17:24 INFO - "builduid": "ce3921cabfad475695eb4dde93b04cbd", 01:17:24 INFO - "repo_path": "releases/mozilla-aurora" 01:17:24 INFO - } 01:17:24 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 01:17:24 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.test_packages.json. 01:17:24 INFO - Running post-action listener: _resource_record_post_action 01:17:24 INFO - [mozharness: 2016-09-20 08:17:24.897298Z] Finished read-buildbot-config step (success) 01:17:24 INFO - [mozharness: 2016-09-20 08:17:24.897388Z] Running download-and-extract step. 01:17:24 INFO - Running pre-action listener: _resource_record_pre_action 01:17:24 INFO - Running main action method: download_and_extract 01:17:24 INFO - mkdir: /builds/slave/test/build/tests 01:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:24 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 01:17:24 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 01:17:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 01:17:24 INFO - Downloaded 1361 bytes. 01:17:24 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 01:17:24 INFO - Using the following test package requirements: 01:17:24 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 01:17:24 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 01:17:24 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 01:17:24 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 01:17:24 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 01:17:24 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 01:17:24 INFO - u'jsshell-linux-x86_64.zip'], 01:17:24 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 01:17:24 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 01:17:24 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 01:17:24 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 01:17:24 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 01:17:24 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 01:17:24 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 01:17:24 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 01:17:24 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 01:17:24 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 01:17:24 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 01:17:24 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:17:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 01:17:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:17:24 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 01:17:25 INFO - Expected file size: 21893574 01:17:29 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:17:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 01:17:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:17:29 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 01:17:29 INFO - Expected file size: 21893574 01:17:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 01:17:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:17:35 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 01:17:35 INFO - Expected file size: 14414059 01:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:35 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 01:17:35 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 01:17:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:17:37 INFO - Downloaded 63918632 bytes. 01:17:37 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 01:17:37 INFO - mkdir: /builds/slave/test/properties 01:17:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:17:37 INFO - Writing to file /builds/slave/test/properties/build_url 01:17:37 INFO - Contents: 01:17:37 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 01:17:37 INFO - Running post-action listener: _resource_record_post_action 01:17:37 INFO - Running post-action listener: set_extra_try_arguments 01:17:37 INFO - [mozharness: 2016-09-20 08:17:37.252878Z] Finished download-and-extract step (success) 01:17:37 INFO - [mozharness: 2016-09-20 08:17:37.253109Z] Running populate-webroot step. 01:17:37 INFO - Running pre-action listener: _resource_record_pre_action 01:17:37 INFO - Running main action method: populate_webroot 01:17:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:17:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:17:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:17:37 INFO - '--tppagecycles', 01:17:37 INFO - '7'], 01:17:37 INFO - 'tests': ['tsvgm']}}, 01:17:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:17:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:17:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:17:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:17:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:17:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:17:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:37 INFO - 'talos_options': ['--disable-e10s'], 01:17:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:17:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:17:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:37 INFO - 'talos_options': ['--disable-e10s'], 01:17:37 INFO - 'tests': ['damp', 'tps']}, 01:17:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:37 INFO - 'tests': ['damp', 'tps']}, 01:17:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:17:37 INFO - 'tests': ['dromaeo_dom']}, 01:17:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:17:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:17:37 INFO - 'tests': ['basic_compositor_video']}, 01:17:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:17:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:17:37 INFO - 'tests': ['a11yr', 01:17:37 INFO - 'ts_paint', 01:17:37 INFO - 'tpaint', 01:17:37 INFO - 'sessionrestore', 01:17:37 INFO - 'sessionrestore_no_auto_restore', 01:17:37 INFO - 'tabpaint']}, 01:17:37 INFO - 'other-e10s': {'tests': ['a11yr', 01:17:37 INFO - 'ts_paint', 01:17:37 INFO - 'tpaint', 01:17:37 INFO - 'sessionrestore', 01:17:37 INFO - 'sessionrestore_no_auto_restore', 01:17:37 INFO - 'tabpaint']}, 01:17:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:17:37 INFO - 'ts_paint', 01:17:37 INFO - 'tpaint', 01:17:37 INFO - 'sessionrestore', 01:17:37 INFO - 'sessionrestore_no_auto_restore', 01:17:37 INFO - 'tabpaint']}, 01:17:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:17:37 INFO - 'ts_paint', 01:17:37 INFO - 'tpaint', 01:17:37 INFO - 'sessionrestore', 01:17:37 INFO - 'sessionrestore_no_auto_restore', 01:17:37 INFO - 'tabpaint']}, 01:17:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:17:37 INFO - 'tests': ['a11yr', 01:17:37 INFO - 'ts_paint', 01:17:37 INFO - 'tpaint', 01:17:37 INFO - 'sessionrestore', 01:17:37 INFO - 'sessionrestore_no_auto_restore', 01:17:37 INFO - 'tabpaint']}, 01:17:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:17:37 INFO - 'tests': ['a11yr', 01:17:37 INFO - 'ts_paint', 01:17:37 INFO - 'tpaint', 01:17:37 INFO - 'sessionrestore', 01:17:37 INFO - 'sessionrestore_no_auto_restore', 01:17:37 INFO - 'tabpaint']}, 01:17:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:17:37 INFO - 'tests': ['tsvgx', 01:17:37 INFO - 'tsvgr_opacity', 01:17:37 INFO - 'tart', 01:17:37 INFO - 'tscrollx', 01:17:37 INFO - 'cart']}, 01:17:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:17:37 INFO - 'tsvgr_opacity', 01:17:37 INFO - 'tart', 01:17:37 INFO - 'tscrollx', 01:17:37 INFO - 'cart']}, 01:17:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:37 INFO - 'talos_options': ['--disable-e10s'], 01:17:37 INFO - 'tests': ['tp5o']}, 01:17:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:37 INFO - 'tests': ['tp5o']}, 01:17:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:37 INFO - 'talos_options': ['--disable-e10s', 01:17:37 INFO - '--xperf_path', 01:17:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:17:37 INFO - 'tests': ['tp5n']}, 01:17:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:17:37 INFO - 'talos_options': ['--xperf_path', 01:17:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:17:37 INFO - 'tests': ['tp5n']}}, 01:17:37 INFO - 'talos.zip': {'path': '', 01:17:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:17:37 INFO - Running post-action listener: _resource_record_post_action 01:17:37 INFO - [mozharness: 2016-09-20 08:17:37.274749Z] Finished populate-webroot step (success) 01:17:37 INFO - [mozharness: 2016-09-20 08:17:37.274839Z] Running create-virtualenv step. 01:17:37 INFO - Running pre-action listener: _resource_record_pre_action 01:17:37 INFO - Running main action method: create_virtualenv 01:17:37 INFO - Creating virtualenv /builds/slave/test/build/venv 01:17:37 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 01:17:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:17:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:17:37 INFO - Using real prefix '/usr' 01:17:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:17:37 INFO - Installing distribute.............................................................................................................................................................................................done. 01:17:39 INFO - Installing pip.................done. 01:17:39 INFO - Return code: 0 01:17:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:17:39 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')]} 01:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:39 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')]} 01:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:39 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 0x7f690626df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1169ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1166f60>, '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 0x1167d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11681d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', '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 01:17:39 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 01:17:39 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 01:17:39 INFO - Using env: {'DISPLAY': ':0', 01:17:39 INFO - 'HOME': '/home/cltbld', 01:17:39 INFO - 'LANG': 'en_US.UTF-8', 01:17:39 INFO - 'LANGUAGE': 'en_US:en', 01:17:39 INFO - 'LOGNAME': 'cltbld', 01:17:39 INFO - 'MAIL': '/var/mail/cltbld', 01:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:39 INFO - 'MOZ_NO_REMOTE': '1', 01:17:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:17:39 INFO - 'NO_EM_RESTART': '1', 01:17:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:39 INFO - 'PWD': '/builds/slave/test', 01:17:39 INFO - 'SHELL': '/bin/bash', 01:17:39 INFO - 'SHLVL': '1', 01:17:39 INFO - 'TERM': 'linux', 01:17:39 INFO - 'TMOUT': '86400', 01:17:39 INFO - 'USER': 'cltbld', 01:17:39 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', 01:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:39 INFO - '_': '/tools/buildbot/bin/python'} 01:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:40 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 01:17:40 INFO - Cleaning up... 01:17:40 INFO - Return code: 0 01:17:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:40 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 0x7f690626df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1169ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1166f60>, '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 0x1167d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11681d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', '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 01:17:40 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 01:17:40 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 01:17:40 INFO - Using env: {'DISPLAY': ':0', 01:17:40 INFO - 'HOME': '/home/cltbld', 01:17:40 INFO - 'LANG': 'en_US.UTF-8', 01:17:40 INFO - 'LANGUAGE': 'en_US:en', 01:17:40 INFO - 'LOGNAME': 'cltbld', 01:17:40 INFO - 'MAIL': '/var/mail/cltbld', 01:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:40 INFO - 'MOZ_NO_REMOTE': '1', 01:17:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:17:40 INFO - 'NO_EM_RESTART': '1', 01:17:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:40 INFO - 'PWD': '/builds/slave/test', 01:17:40 INFO - 'SHELL': '/bin/bash', 01:17:40 INFO - 'SHLVL': '1', 01:17:40 INFO - 'TERM': 'linux', 01:17:40 INFO - 'TMOUT': '86400', 01:17:40 INFO - 'USER': 'cltbld', 01:17:40 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', 01:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:40 INFO - '_': '/tools/buildbot/bin/python'} 01:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:40 INFO - Downloading/unpacking psutil>=3.1.1 01:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:17:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:17:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:17:42 INFO - Installing collected packages: psutil 01:17:42 INFO - Running setup.py install for psutil 01:17:42 INFO - building 'psutil._psutil_linux' extension 01:17:42 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 01:17:42 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 01:17:42 INFO - building 'psutil._psutil_posix' extension 01:17:42 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 01:17:42 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 01:17:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:17:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:17:42 INFO - Successfully installed psutil 01:17:42 INFO - Cleaning up... 01:17:42 INFO - Return code: 0 01:17:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:17:42 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')]} 01:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:42 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')]} 01:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:42 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 0x7f690626df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1169ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1166f60>, '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 0x1167d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11681d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', '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 01:17:42 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 01:17:42 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 01:17:42 INFO - Using env: {'DISPLAY': ':0', 01:17:42 INFO - 'HOME': '/home/cltbld', 01:17:42 INFO - 'LANG': 'en_US.UTF-8', 01:17:42 INFO - 'LANGUAGE': 'en_US:en', 01:17:42 INFO - 'LOGNAME': 'cltbld', 01:17:42 INFO - 'MAIL': '/var/mail/cltbld', 01:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:42 INFO - 'MOZ_NO_REMOTE': '1', 01:17:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:17:42 INFO - 'NO_EM_RESTART': '1', 01:17:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:42 INFO - 'PWD': '/builds/slave/test', 01:17:42 INFO - 'SHELL': '/bin/bash', 01:17:42 INFO - 'SHLVL': '1', 01:17:42 INFO - 'TERM': 'linux', 01:17:42 INFO - 'TMOUT': '86400', 01:17:42 INFO - 'USER': 'cltbld', 01:17:42 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', 01:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:42 INFO - '_': '/tools/buildbot/bin/python'} 01:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:17:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:17:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:17:44 INFO - Installing collected packages: mozsystemmonitor 01:17:44 INFO - Running setup.py install for mozsystemmonitor 01:17:45 INFO - Successfully installed mozsystemmonitor 01:17:45 INFO - Cleaning up... 01:17:45 INFO - Return code: 0 01:17:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:17:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:45 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 0x7f690626df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1169ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1166f60>, '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 0x1167d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11681d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', '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 01:17:45 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 01:17:45 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 01:17:45 INFO - Using env: {'DISPLAY': ':0', 01:17:45 INFO - 'HOME': '/home/cltbld', 01:17:45 INFO - 'LANG': 'en_US.UTF-8', 01:17:45 INFO - 'LANGUAGE': 'en_US:en', 01:17:45 INFO - 'LOGNAME': 'cltbld', 01:17:45 INFO - 'MAIL': '/var/mail/cltbld', 01:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:45 INFO - 'MOZ_NO_REMOTE': '1', 01:17:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:17:45 INFO - 'NO_EM_RESTART': '1', 01:17:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:45 INFO - 'PWD': '/builds/slave/test', 01:17:45 INFO - 'SHELL': '/bin/bash', 01:17:45 INFO - 'SHLVL': '1', 01:17:45 INFO - 'TERM': 'linux', 01:17:45 INFO - 'TMOUT': '86400', 01:17:45 INFO - 'USER': 'cltbld', 01:17:45 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', 01:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:45 INFO - '_': '/tools/buildbot/bin/python'} 01:17:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:45 INFO - Downloading/unpacking jsonschema==2.5.1 01:17:45 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 01:17:45 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 01:17:45 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 01:17:45 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 01:17:45 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 01:17:45 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 01:17:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:17:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:17:47 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 01:17:47 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 01:17:47 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 01:17:47 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 01:17:47 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 01:17:47 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 01:17:47 INFO - Downloading functools32-3.2.3-2.tar.gz 01:17:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:17:47 INFO - warning: no files found matching '*.txt' 01:17:47 INFO - no previously-included directories found matching 'build' 01:17:47 INFO - no previously-included directories found matching 'dist' 01:17:47 INFO - no previously-included directories found matching '.git*' 01:17:47 INFO - Installing collected packages: jsonschema, functools32 01:17:47 INFO - Running setup.py install for functools32 01:17:47 INFO - warning: no files found matching '*.txt' 01:17:47 INFO - no previously-included directories found matching 'build' 01:17:47 INFO - no previously-included directories found matching 'dist' 01:17:47 INFO - no previously-included directories found matching '.git*' 01:17:47 INFO - Successfully installed jsonschema functools32 01:17:47 INFO - Cleaning up... 01:17:47 INFO - Return code: 0 01:17:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:17:47 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')]} 01:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:47 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')]} 01:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:47 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 0x7f690626df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1169ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1166f60>, '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 0x1167d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11681d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', '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 01:17:47 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 01:17:47 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 01:17:47 INFO - Using env: {'DISPLAY': ':0', 01:17:47 INFO - 'HOME': '/home/cltbld', 01:17:47 INFO - 'LANG': 'en_US.UTF-8', 01:17:47 INFO - 'LANGUAGE': 'en_US:en', 01:17:47 INFO - 'LOGNAME': 'cltbld', 01:17:47 INFO - 'MAIL': '/var/mail/cltbld', 01:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:47 INFO - 'MOZ_NO_REMOTE': '1', 01:17:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:17:47 INFO - 'NO_EM_RESTART': '1', 01:17:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:47 INFO - 'PWD': '/builds/slave/test', 01:17:47 INFO - 'SHELL': '/bin/bash', 01:17:47 INFO - 'SHLVL': '1', 01:17:47 INFO - 'TERM': 'linux', 01:17:47 INFO - 'TMOUT': '86400', 01:17:47 INFO - 'USER': 'cltbld', 01:17:47 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', 01:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:47 INFO - '_': '/tools/buildbot/bin/python'} 01:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:17:47 INFO - Cleaning up... 01:17:47 INFO - Return code: 0 01:17:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:17:47 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')]} 01:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:47 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')]} 01:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:47 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 0x7f690626df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1169ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1166f60>, '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 0x1167d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11681d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', '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 01:17:47 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 01:17:47 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 01:17:47 INFO - Using env: {'DISPLAY': ':0', 01:17:47 INFO - 'HOME': '/home/cltbld', 01:17:47 INFO - 'LANG': 'en_US.UTF-8', 01:17:47 INFO - 'LANGUAGE': 'en_US:en', 01:17:47 INFO - 'LOGNAME': 'cltbld', 01:17:47 INFO - 'MAIL': '/var/mail/cltbld', 01:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:47 INFO - 'MOZ_NO_REMOTE': '1', 01:17:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:17:47 INFO - 'NO_EM_RESTART': '1', 01:17:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:47 INFO - 'PWD': '/builds/slave/test', 01:17:47 INFO - 'SHELL': '/bin/bash', 01:17:47 INFO - 'SHLVL': '1', 01:17:47 INFO - 'TERM': 'linux', 01:17:47 INFO - 'TMOUT': '86400', 01:17:47 INFO - 'USER': 'cltbld', 01:17:47 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', 01:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:47 INFO - '_': '/tools/buildbot/bin/python'} 01:17:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:48 INFO - Downloading/unpacking blobuploader==1.2.4 01:17:48 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 01:17:48 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 01:17:48 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 01:17:48 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 01:17:48 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 01:17:48 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 01:17:50 INFO - Downloading blobuploader-1.2.4.tar.gz 01:17:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:17:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:17:50 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 01:17:50 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 01:17:50 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 01:17:50 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 01:17:50 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 01:17:50 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 01:17:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:17:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:17:50 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 01:17:50 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 01:17:50 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 01:17:50 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 01:17:50 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 01:17:50 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 01:17:50 INFO - Downloading docopt-0.6.1.tar.gz 01:17:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:17:50 INFO - Installing collected packages: blobuploader, requests, docopt 01:17:50 INFO - Running setup.py install for blobuploader 01:17:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:17:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:17:50 INFO - Running setup.py install for requests 01:17:51 INFO - Running setup.py install for docopt 01:17:51 INFO - Successfully installed blobuploader requests docopt 01:17:51 INFO - Cleaning up... 01:17:51 INFO - Return code: 0 01:17:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:51 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 0x7f690626df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1169ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1166f60>, '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 0x1167d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11681d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', '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 01:17:51 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 01:17:51 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 01:17:51 INFO - Using env: {'DISPLAY': ':0', 01:17:51 INFO - 'HOME': '/home/cltbld', 01:17:51 INFO - 'LANG': 'en_US.UTF-8', 01:17:51 INFO - 'LANGUAGE': 'en_US:en', 01:17:51 INFO - 'LOGNAME': 'cltbld', 01:17:51 INFO - 'MAIL': '/var/mail/cltbld', 01:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:51 INFO - 'MOZ_NO_REMOTE': '1', 01:17:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:17:51 INFO - 'NO_EM_RESTART': '1', 01:17:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:17:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:51 INFO - 'PWD': '/builds/slave/test', 01:17:51 INFO - 'SHELL': '/bin/bash', 01:17:51 INFO - 'SHLVL': '1', 01:17:51 INFO - 'TERM': 'linux', 01:17:51 INFO - 'TMOUT': '86400', 01:17:51 INFO - 'USER': 'cltbld', 01:17:51 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', 01:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:51 INFO - '_': '/tools/buildbot/bin/python'} 01:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:17:51 INFO - Running setup.py (path:/tmp/pip-JdFmZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:17:51 INFO - Running setup.py (path:/tmp/pip-wu3Cgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:17:51 INFO - Running setup.py (path:/tmp/pip-ynlqOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:17:51 INFO - Running setup.py (path:/tmp/pip-mSTCLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:17:51 INFO - Running setup.py (path:/tmp/pip-pP9Wx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:17:52 INFO - Running setup.py (path:/tmp/pip-gaTKZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:17:52 INFO - Running setup.py (path:/tmp/pip-W67YDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:17:52 INFO - Running setup.py (path:/tmp/pip-CcBH_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:17:52 INFO - Running setup.py (path:/tmp/pip-aBlWOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:17:52 INFO - Running setup.py (path:/tmp/pip-gdt1TH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:17:52 INFO - Running setup.py (path:/tmp/pip-l6NA36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:17:52 INFO - Running setup.py (path:/tmp/pip-45nng4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:17:52 INFO - Running setup.py (path:/tmp/pip-howqqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:17:52 INFO - Running setup.py (path:/tmp/pip-nyaCl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:17:52 INFO - Running setup.py (path:/tmp/pip-_E9FgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:17:52 INFO - Running setup.py (path:/tmp/pip-TerGzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:17:52 INFO - Running setup.py (path:/tmp/pip-gjG6fY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:17:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:17:52 INFO - Running setup.py install for manifestparser 01:17:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:17:53 INFO - Running setup.py install for mozcrash 01:17:53 INFO - Running setup.py install for mozdebug 01:17:53 INFO - Running setup.py install for mozdevice 01:17:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:17:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:17:53 INFO - Running setup.py install for mozfile 01:17:53 INFO - Running setup.py install for mozhttpd 01:17:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:17:53 INFO - Running setup.py install for mozinfo 01:17:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:17:53 INFO - Running setup.py install for mozInstall 01:17:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:17:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:17:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:17:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:17:53 INFO - Running setup.py install for mozleak 01:17:53 INFO - Running setup.py install for mozlog 01:17:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:17:53 INFO - Running setup.py install for moznetwork 01:17:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:17:54 INFO - Running setup.py install for mozprocess 01:17:54 INFO - Running setup.py install for mozprofile 01:17:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:17:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:17:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:17:54 INFO - Running setup.py install for mozrunner 01:17:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:17:54 INFO - Running setup.py install for mozscreenshot 01:17:54 INFO - Running setup.py install for moztest 01:17:54 INFO - Running setup.py install for mozversion 01:17:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:17:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:17:54 INFO - Cleaning up... 01:17:54 INFO - Return code: 0 01:17:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:54 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 0x7f690626df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1169ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1166f60>, '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 0x1167d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11681d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', '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 01:17:54 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 01:17:54 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 01:17:54 INFO - Using env: {'DISPLAY': ':0', 01:17:54 INFO - 'HOME': '/home/cltbld', 01:17:54 INFO - 'LANG': 'en_US.UTF-8', 01:17:54 INFO - 'LANGUAGE': 'en_US:en', 01:17:54 INFO - 'LOGNAME': 'cltbld', 01:17:54 INFO - 'MAIL': '/var/mail/cltbld', 01:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:54 INFO - 'MOZ_NO_REMOTE': '1', 01:17:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:17:54 INFO - 'NO_EM_RESTART': '1', 01:17:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:17:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:54 INFO - 'PWD': '/builds/slave/test', 01:17:54 INFO - 'SHELL': '/bin/bash', 01:17:54 INFO - 'SHLVL': '1', 01:17:54 INFO - 'TERM': 'linux', 01:17:54 INFO - 'TMOUT': '86400', 01:17:54 INFO - 'USER': 'cltbld', 01:17:54 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', 01:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:54 INFO - '_': '/tools/buildbot/bin/python'} 01:17:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:17:54 INFO - Running setup.py (path:/tmp/pip-oTwK8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:17:55 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)) 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:17:55 INFO - Running setup.py (path:/tmp/pip-nSWrr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:17:55 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)) 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:17:55 INFO - Running setup.py (path:/tmp/pip-toVpO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:17:55 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)) 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:17:55 INFO - Running setup.py (path:/tmp/pip-2dMMpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:17:55 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)) 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:17:55 INFO - Running setup.py (path:/tmp/pip-qKUVZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:17:55 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)) 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:17:55 INFO - Running setup.py (path:/tmp/pip-MKIlNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:17:55 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)) 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:17:55 INFO - Running setup.py (path:/tmp/pip-_Nf6XR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:17:55 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)) 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:17:55 INFO - Running setup.py (path:/tmp/pip-8eIPYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:17:55 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)) 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:17:55 INFO - Running setup.py (path:/tmp/pip-YL59Ci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:17:55 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)) 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:17:55 INFO - Running setup.py (path:/tmp/pip-reyerI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:17:55 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)) 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:17:55 INFO - Running setup.py (path:/tmp/pip-A30NmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:17:55 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)) 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:17:55 INFO - Running setup.py (path:/tmp/pip-0x8CCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:17:55 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)) 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:17:55 INFO - Running setup.py (path:/tmp/pip-9le4cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:17:55 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)) 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:17:55 INFO - Running setup.py (path:/tmp/pip-WBCyPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:17:55 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)) 01:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:17:55 INFO - Running setup.py (path:/tmp/pip-UN5ZAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:17:56 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)) 01:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:17:56 INFO - Running setup.py (path:/tmp/pip-rRCqJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:17:56 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)) 01:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:17:56 INFO - Running setup.py (path:/tmp/pip-NMRMLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:17:56 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)) 01:17:56 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)) 01:17:56 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)) 01:17:56 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)) 01:17:56 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)) 01:17:56 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)) 01:17:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:17:56 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 01:17:56 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 01:17:56 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 01:17:56 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 01:17:56 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 01:17:56 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 01:17:58 INFO - Downloading blessings-1.6.tar.gz 01:17:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:17:58 INFO - Installing collected packages: blessings 01:17:58 INFO - Running setup.py install for blessings 01:17:58 INFO - Successfully installed blessings 01:17:58 INFO - Cleaning up... 01:17:58 INFO - Return code: 0 01:17:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:17:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:17:58 INFO - Reading from file tmpfile_stdout 01:17:58 INFO - Current package versions: 01:17:58 INFO - argparse == 1.2.1 01:17:58 INFO - blessings == 1.6 01:17:58 INFO - blobuploader == 1.2.4 01:17:58 INFO - docopt == 0.6.1 01:17:58 INFO - functools32 == 3.2.3-2 01:17:58 INFO - jsonschema == 2.5.1 01:17:58 INFO - manifestparser == 1.1 01:17:58 INFO - mozInstall == 1.12 01:17:58 INFO - mozcrash == 0.17 01:17:58 INFO - mozdebug == 0.1 01:17:58 INFO - mozdevice == 0.48 01:17:58 INFO - mozfile == 1.2 01:17:58 INFO - mozhttpd == 0.7 01:17:58 INFO - mozinfo == 0.9 01:17:58 INFO - mozleak == 0.1 01:17:58 INFO - mozlog == 3.4 01:17:58 INFO - moznetwork == 0.27 01:17:58 INFO - mozprocess == 0.23 01:17:58 INFO - mozprofile == 0.28 01:17:58 INFO - mozrunner == 6.12 01:17:58 INFO - mozscreenshot == 0.1 01:17:58 INFO - mozsystemmonitor == 0.3 01:17:58 INFO - moztest == 0.7 01:17:58 INFO - mozversion == 1.4 01:17:58 INFO - psutil == 3.1.1 01:17:58 INFO - requests == 1.2.3 01:17:58 INFO - wsgiref == 0.1.2 01:17:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:58 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 0x7f690626df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1169ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1166f60>, '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 0x1167d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11681d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', '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 01:17:58 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 01:17:58 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 01:17:58 INFO - Using env: {'DISPLAY': ':0', 01:17:58 INFO - 'HOME': '/home/cltbld', 01:17:58 INFO - 'LANG': 'en_US.UTF-8', 01:17:58 INFO - 'LANGUAGE': 'en_US:en', 01:17:58 INFO - 'LOGNAME': 'cltbld', 01:17:58 INFO - 'MAIL': '/var/mail/cltbld', 01:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:58 INFO - 'MOZ_NO_REMOTE': '1', 01:17:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:17:58 INFO - 'NO_EM_RESTART': '1', 01:17:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:58 INFO - 'PWD': '/builds/slave/test', 01:17:58 INFO - 'SHELL': '/bin/bash', 01:17:58 INFO - 'SHLVL': '1', 01:17:58 INFO - 'TERM': 'linux', 01:17:58 INFO - 'TMOUT': '86400', 01:17:58 INFO - 'USER': 'cltbld', 01:17:58 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', 01:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:58 INFO - '_': '/tools/buildbot/bin/python'} 01:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:58 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)) 01:17:58 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)) 01:17:58 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)) 01:17:58 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)) 01:17:58 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)) 01:17:58 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)) 01:17:58 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)) 01:17:58 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)) 01:17:58 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)) 01:17:58 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)) 01:17:58 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)) 01:17:58 INFO - Cleaning up... 01:17:58 INFO - Return code: 0 01:17:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:17:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f690626df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1169ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1166f60>, '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 0x1167d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11681d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', '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 01:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:17:58 INFO - Using env: {'DISPLAY': ':0', 01:17:58 INFO - 'HOME': '/home/cltbld', 01:17:58 INFO - 'LANG': 'en_US.UTF-8', 01:17:58 INFO - 'LANGUAGE': 'en_US:en', 01:17:58 INFO - 'LOGNAME': 'cltbld', 01:17:58 INFO - 'MAIL': '/var/mail/cltbld', 01:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:58 INFO - 'MOZ_NO_REMOTE': '1', 01:17:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:17:58 INFO - 'NO_EM_RESTART': '1', 01:17:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:58 INFO - 'PWD': '/builds/slave/test', 01:17:58 INFO - 'SHELL': '/bin/bash', 01:17:58 INFO - 'SHLVL': '1', 01:17:58 INFO - 'TERM': 'linux', 01:17:58 INFO - 'TMOUT': '86400', 01:17:58 INFO - 'USER': 'cltbld', 01:17:58 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', 01:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:58 INFO - '_': '/tools/buildbot/bin/python'} 01:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:17:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:17:58 INFO - Cleaning up... 01:17:58 INFO - Return code: 0 01:17:58 INFO - Running post-action listener: _resource_record_post_action 01:17:58 INFO - Running post-action listener: _start_resource_monitoring 01:17:59 INFO - Starting resource monitoring. 01:17:59 INFO - [mozharness: 2016-09-20 08:17:59.012752Z] Finished create-virtualenv step (success) 01:17:59 INFO - [mozharness: 2016-09-20 08:17:59.013208Z] Running install step. 01:17:59 INFO - Running pre-action listener: _resource_record_pre_action 01:17:59 INFO - Running main action method: install 01:17:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:17:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:17:59 INFO - Reading from file tmpfile_stdout 01:17:59 INFO - Detecting whether we're running mozinstall >=1.0... 01:17:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:17:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:17:59 INFO - Reading from file tmpfile_stdout 01:17:59 INFO - Output received: 01:17:59 INFO - Usage: mozinstall [options] installer 01:17:59 INFO - Options: 01:17:59 INFO - -h, --help show this help message and exit 01:17:59 INFO - -d DEST, --destination=DEST 01:17:59 INFO - Directory to install application into. [default: 01:17:59 INFO - "/builds/slave/test"] 01:17:59 INFO - --app=APP Application being installed. [default: firefox] 01:17:59 INFO - mkdir: /builds/slave/test/build/application 01:17:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:17:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 01:18:15 INFO - Reading from file tmpfile_stdout 01:18:15 INFO - Output received: 01:18:15 INFO - /builds/slave/test/build/application/firefox/firefox 01:18:15 INFO - Running post-action listener: _resource_record_post_action 01:18:15 INFO - [mozharness: 2016-09-20 08:18:15.297441Z] Finished install step (success) 01:18:15 INFO - [mozharness: 2016-09-20 08:18:15.297690Z] Running run-tests step. 01:18:15 INFO - Running pre-action listener: _resource_record_pre_action 01:18:15 INFO - Running main action method: run_tests 01:18:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:18:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:18:15 INFO - Python 2.7.3 01:18:15 INFO - Return code: 0 01:18:15 INFO - grabbing minidump binary from tooltool 01:18:15 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')]} 01:18:15 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 0x1166f60>, '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 0x1167d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11681d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:18:15 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 01:18:15 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 01:18:15 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 01:18:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:18:15 INFO - Return code: 0 01:18:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:18:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:18:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:18:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:18:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:18:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 01:18:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --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 01:18:15 INFO - Using env: {'DISPLAY': ':0', 01:18:15 INFO - 'HOME': '/home/cltbld', 01:18:15 INFO - 'LANG': 'en_US.UTF-8', 01:18:15 INFO - 'LANGUAGE': 'en_US:en', 01:18:15 INFO - 'LOGNAME': 'cltbld', 01:18:15 INFO - 'MAIL': '/var/mail/cltbld', 01:18:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:18:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:18:15 INFO - 'MOZ_NO_REMOTE': '1', 01:18:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:18:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:18:15 INFO - 'NO_EM_RESTART': '1', 01:18:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:18:15 INFO - 'PWD': '/builds/slave/test', 01:18:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:18:15 INFO - 'SHELL': '/bin/bash', 01:18:15 INFO - 'SHLVL': '1', 01:18:15 INFO - 'TERM': 'linux', 01:18:15 INFO - 'TMOUT': '86400', 01:18:15 INFO - 'USER': 'cltbld', 01:18:15 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693', 01:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:18:15 INFO - '_': '/tools/buildbot/bin/python'} 01:18:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 01:18:15 INFO - mozversion application_buildid: 20160919151817 01:18:15 INFO - mozversion application_changeset: c429089a8e66cff46a0fba433acee27f08057034 01:18:15 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:18:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:18:15 INFO - mozversion application_name: Firefox 01:18:15 INFO - mozversion application_remotingname: firefox-dev 01:18:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:18:15 INFO - mozversion application_vendor: Mozilla 01:18:15 INFO - mozversion application_version: 51.0a2 01:18:15 INFO - mozversion platform_buildid: 20160919151817 01:18:15 INFO - mozversion platform_changeset: c429089a8e66cff46a0fba433acee27f08057034 01:18:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:18:15 INFO - mozversion platform_version: 51.0a2 01:18:15 INFO - using testdate: 1474359495 01:18:15 INFO - actual date: 1474359495 01:18:15 INFO - starting webserver on 'localhost:59197' 01:18:15 INFO - SUITE-START | Running 2 tests 01:18:15 INFO - TEST-START | dromaeo_css 01:18:15 INFO - operating with platform_type : linux_ 01:18:15 INFO - Initialising browser for dromaeo_css test... 01:18:15 INFO - TEST-INFO | started process 2508 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprDvCxs/profile http://localhost:59197/getInfo.html) 01:18:19 INFO - PROCESS | 2508 | __metrics Screen width/height:1600/1200 01:18:19 INFO - PROCESS | 2508 | colorDepth:24 01:18:19 INFO - PROCESS | 2508 | Browser inner width/height: 1024/697 01:18:19 INFO - PROCESS | 2508 | __metrics 01:18:20 INFO - PROCESS | 2508 | 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" 01:18:20 INFO - PROCESS | 2508 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:18:20 INFO - PROCESS | 2508 | console.error: PushService: 01:18:20 INFO - PROCESS | 2508 | stateChangeProcessEnqueue: Error transitioning state 01:18:20 INFO - PROCESS | 2508 | UnknownError 01:18:21 INFO - TEST-INFO | 2508: exit 0 01:18:21 INFO - Browser initialized. 01:18:21 INFO - Running cycle 1/1 for dromaeo_css test... 01:18:21 INFO - TEST-INFO | started process 2617 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprDvCxs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:18:33 INFO - PROCESS | 2617 | RSS: Main: 147296256 01:18:33 INFO - PROCESS | 2617 | 01:19:24 INFO - PROCESS | 2617 | 1474359564339 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] 01:19:46 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01651 seconds. 01:19:52 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.0337 seconds. 01:19:59 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.03273 seconds. 01:20:04 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 01:20:06 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.16663 seconds. 01:20:08 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01651 seconds. 01:20:10 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.04968 seconds. 01:20:17 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.03287 seconds. 01:20:28 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.05 seconds. 01:20:33 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.03289 seconds. 01:20:53 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00026 seconds. 01:21:06 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.03344 seconds. 01:21:19 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01659 seconds. 01:21:21 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01659 seconds. 01:21:23 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01651 seconds. 01:21:30 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.0005 seconds. 01:21:33 INFO - PROCESS | 2617 | Cycle 1(1): loaded http://localhost:59197/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59197/tests/dromaeo/cssquery-ext.html) 01:21:34 INFO - PROCESS | 2617 | RSS: Main: 243634176 01:21:34 INFO - PROCESS | 2617 | 01:21:37 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.0675 seconds. 01:21:38 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.0168 seconds. 01:22:15 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01654 seconds. 01:22:25 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01634 seconds. 01:22:46 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01692 seconds. 01:23:01 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.0002 seconds. 01:23:24 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00046 seconds. 01:23:26 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 01:23:37 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 01:23:56 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00052 seconds. 01:24:04 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 01:24:12 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 01:24:16 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00021 seconds. 01:24:24 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00035 seconds. 01:24:28 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 01:24:36 INFO - PROCESS | 2617 | Cycle 1(1): loaded http://localhost:59197/tests/dromaeo/cssquery-ext.html (next: http://localhost:59197/tests/dromaeo/cssquery-jquery.html) 01:24:37 INFO - PROCESS | 2617 | RSS: Main: 232427520 01:24:37 INFO - PROCESS | 2617 | 01:24:40 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01685 seconds. 01:24:43 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.05 seconds. 01:25:02 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00481 seconds. 01:25:20 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 01:25:32 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00022 seconds. 01:26:09 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 01:26:14 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00056 seconds. 01:26:16 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 01:26:20 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 01:26:26 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01603 seconds. 01:26:36 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00019 seconds. 01:26:56 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.0166 seconds. 01:27:01 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00047 seconds. 01:27:25 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 01:27:33 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.0002 seconds. 01:27:39 INFO - PROCESS | 2617 | Cycle 1(1): loaded http://localhost:59197/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59197/tests/dromaeo/cssquery-mootools.html) 01:27:40 INFO - PROCESS | 2617 | RSS: Main: 223850496 01:27:40 INFO - PROCESS | 2617 | 01:27:43 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01654 seconds. 01:27:56 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.0164 seconds. 01:28:31 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00077 seconds. 01:28:51 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 01:29:05 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 01:29:07 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00031 seconds. 01:30:09 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00047 seconds. 01:30:27 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 01:30:36 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 01:30:42 INFO - PROCESS | 2617 | Cycle 1(1): loaded http://localhost:59197/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59197/tests/dromaeo/cssquery-prototype.html) 01:30:42 INFO - PROCESS | 2617 | RSS: Main: 175906816 01:30:42 INFO - PROCESS | 2617 | 01:30:59 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01635 seconds. 01:31:29 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 01:31:31 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00014 seconds. 01:31:34 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01684 seconds. 01:31:37 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00035 seconds. 01:31:41 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00046 seconds. 01:31:56 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 01:32:08 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00043 seconds. 01:32:10 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00028 seconds. 01:32:26 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00058 seconds. 01:32:32 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00027 seconds. 01:32:37 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 01:32:42 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00023 seconds. 01:32:46 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01663 seconds. 01:32:50 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01623 seconds. 01:33:03 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 01:33:35 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 01:33:45 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00035 seconds. 01:33:45 INFO - PROCESS | 2617 | Cycle 1(1): loaded http://localhost:59197/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59197/tests/dromaeo/cssquery-yui.html) 01:33:45 INFO - PROCESS | 2617 | RSS: Main: 175665152 01:33:45 INFO - PROCESS | 2617 | 01:33:51 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.04984 seconds. 01:34:02 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01703 seconds. 01:34:18 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.0166 seconds. 01:34:44 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00037 seconds. 01:34:49 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00046 seconds. 01:34:53 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.00037 seconds. 01:35:20 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01599 seconds. 01:35:30 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01643 seconds. 01:35:35 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01641 seconds. 01:35:51 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01581 seconds. 01:36:16 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01709 seconds. 01:36:28 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01663 seconds. 01:36:42 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 01:36:44 INFO - PROCESS | 2617 | [GFX1-]: Refresh driver waiting for the compositor for 1.01687 seconds. 01:36:48 INFO - PROCESS | 2617 | Cycle 1(1): loaded http://localhost:59197/tests/dromaeo/cssquery-yui.html (next: http://localhost:59197/tests/dromaeo/cssquery-dojo.html) 01:36:48 INFO - PROCESS | 2617 | RSS: Main: 259514368 01:36:48 INFO - PROCESS | 2617 | 01:36:48 INFO - PROCESS | 2617 | __start_tp_report 01:36:48 INFO - PROCESS | 2617 | _x_x_mozilla_page_load 01:36:48 INFO - PROCESS | 2617 | _x_x_mozilla_page_load_details 01:36:48 INFO - PROCESS | 2617 | |i|pagename|runs| 01:36:48 INFO - PROCESS | 2617 | |0;dojo.html;3090.3664748726515;3126.5310203469476;3131.7494600431983;3150.1494596458942;3166.968330316696;5589.832305030851;5668.433156684329;5669.036263835148;5672.631278966865;5697.943020569798;5678.545716342693;5725.742341594628;5748.856278593027;5753.626014309078;5759.827205183851;5110.28456016158;5145.3311069561;5159.948400516003;5174;5193.818216362428;5045.21799121135;5297.231901374298;5298.337707786527;5309.681419114846;5311.867203319909;5032.446430892599;5166.844994650167;5171.456997015315;5187.896242075156;5202;5204.921926171091;5224.242484839696;5252.8686782830355;5317.680939143664;5318.654287471302;5142.485751424865;5190.6107041972;5259.447757985395;5268.473152684739;5293.947060529423;5555.138953462179;5560.749766260487;5589.664620122765;5601;5635.295588051494;5953.226080609536;5966.791162309341;6117.724702388403;6119.816405507842;6121.724522396503;6119.694015299262;6152.384761523812;6158.753649854;6170.2904166021035;6173.135760993465;5478.671279723229;5527.585431092644;5630.5214056805635;5672.829815105553;5674.460926212003;6242.968785156045;6250.062490626442;6287.748490060438;6292.4966002719675;6318.115463835067;6056.909146362808;6070.3929607038945;6077.64777313826;6078.240219972504;6106.877862442726;5899.970500147472;5948.315943666447;5975.372585878507;6033.5474839387225;6047.758089676452;6068.423499767515;6071.757129714851;6073.696315184311;6074.757009719562;6108.35862234459;6023.247094113236;6044.365341639153;6068.120122582201;6078.1186727924205;6104.755809767559;4522.796474158671;4582.679212455096;4597.931031034537;4633.860984170413;4645.419322584677;4133.855315063958;4135.58644135584;4150.06623509714;4150.750954942713;4158.958410415918;5862;5866.413358664099;5867.090600956859;5867.354590994987;5867.3839246879315;5913.556483263773;5966.373530779292;6006.639601623917;6017.157597936292;6166.383361663798;5764.279465066867;5838.562107841929;5840.649561026352;5864.941350586525;5880.441358070976;5284.524392804666;5285.524302812765;5289.523942845162;5295.973520132452;5299.364076310791;6116.35778243278;6133.395871484143;6276.278228003746;6327.841803954938;6333;6043;6128.877422451436;6137.815865524041;6153.815385538441;6201.193844800147;6347.333529979285;6359.904601431075;6361.077643741724;6377.840553986188;6381.521385895983;5762.7694892203845;6062.272527296753;6068.817935461943;6070.666113363631;6073.423024812636;6000.069989151689;6067.635941843503;6127.264728232462;6136.6011209271255;6197.194364732556;5984.251968503937;6034.818955431344;6037.215162029084;6044.455998960115;6067.757289708362;6027.126066720213;6041.697915104315;6042.758289668364;6044.879102418015;6047.758089676364;5672.574556908331;5780.653160810365;5877.4122587742595;5917.289925209003;5920.526357891272;6044.727987240497;6053.424924632153;6062.878742425215;6063.72713227897;6088.543359248163;5689.061304884648;5884.264466941632;5898.292204935436;5900.085486749561;5903 01:36:48 INFO - PROCESS | 2617 | |1;ext.html;555.7626167077907;576.5474102829279;594.7947957954505;601.7803501721871;615.1295916278466;34485.48271775922;37532.62467375326;37541.249175016485;37552.55908365958;37573.06067348321;47946.280805787945;48911.51088489114;48933;52587;52714.20928686073;9167.954160229207;9298.116678915527;9298.62805487783;9302.534873256343;9306.813863722722;8835.249003834682;9218.35471516994;9310.208632266265;9316.534173291342;9316.767080822965;8949.328800339968;11023.779524409507;11286.717832054183;11292.435378231097;11751.236169648988;11304.943475282653;11326.999999999958;11391.202615816896;11409.714757131138;11413.486393112289;8467.49195048299;10920.563177472892;10975;10978.12175025996;10990.835137472945;33390;35866.82066589676;35922;43265;43767.78116109431;3499.720022398202;3657.524521812147;3746.981265093657;3857.614238576148;4302.806373713191;33635;34107.147321316916;34228.65771342305;34534.136646583785;35869.820650896836;4733.526647335273;4817.879553011167;4842.080004799077;4842.903141937177;4865.099956508057;390033;391802;391822;392159;398576;56653;56744.43255567474;56788;56789.43210567924;56839.71580142155;59091;60466.697666512264;65239.34760652428;66173;66422.33577664258;17951.192196350934;18026;18057.367992120475;18066.638667226583;18075.638487230182;14870.925645371704;14972.625684357978;14988.925055374872;14991.025583337048;14994.700105998038;15751;16132.516024519284;17078.658426831393;17357.392491263;17493.65012699739;6314.778982735674;6470.64411457365;6498.155239818888;6626.602403855784;6661.966690166615;1006.6677996261217;1060.390275591538;1076.8546246256656;1140.2588317593631;1145.169751929844;32774.180645484055;32896.52596189765;32902.177445563575;32938.17654558607;33057.66942330594;1128.1707944660668;1150.4995327032768;1153.1870031627682;1195.1574140231116;1201.6034708546333;17221;17604;17731.645367092588;17771.20029598645;17805.7329140063;565109;571215;571859;572127;572695;61125.38874611197;61223.69388153031;61247;61283.69358153181;61306.69346653238;1369.1511263016994;1389.8679625435568;1401.9929900350637;1414.8514405987428;1419.9148051116965;31398;32372.514412284305;32828.1792955178;33036.50445243275;33762.662373375955;53733;54092.45907540874;54171.458285416644;54196.45803542072;54241.72879135579;481909;483278;483992;486155;488273;43382.78308608563;43861.78069109634;45318.32022519599;45409.31886021646;45455;50501;50739.74630126826;50785.23822142597;51159;52356.21465678094;42334.57665423306;42733;43380;44031.339529906436;45070.774646126556;42321.788391057846;42348.36477452779;42484.78757606192;43361.78319108384;44297.11405771931;42177.36733948932;42995.35506967335;43207.135857282046;43253.13493730045;43343;35737.46393804043;37571.358587524475;38428.97413166172;38524;38547;28510.42979140364;28984;29065.273368165967;29300.41399171962;29426.411471770018 01:36:48 INFO - PROCESS | 2617 | |2;jquery.html;403.6911762501685;408.2345601996257;412.4802748536845;421.6394982290142;434.4786256492208;14273.714525709493;16241.83758162418;19930.50173745659;20485.07817148228;20834.374968750923;20308.492287692814;20379.082941267643;20970.056347464364;20995.580088398223;21184.046717897694;5649.49154576087;5820.35976042635;5923.8222853314255;5959.731812068446;5975.282966044081;4073.714839961204;4273.316269396897;5954.940450595482;5958.40415958405;6000.969995150018;4302.892427689317;4534.546545345455;4561.1789877821975;6599.769008084718;6670.0328452374615;5841.766329346821;6644.5681030733085;6645.202575690948;6652.268250492466;6666.466682665399;5923.5261179106;6021.668808215546;6028.276606807167;6030.095485677183;6042.546808989343;15476.22618869052;19030.42908712727;20051.699224511645;24317.635235471484;24424.26727198187;2194.1113848891223;2324.6745455636224;2431.270618814349;2571.485702859417;3031.3331067165186;10735.83896241557;10862.594315864038;11058.391788451632;11061.889381106166;11316;1849.4544109487733;1856.860735444847;1863.0870873272136;1900.5628705397698;1932.5555122321805;67077;70892.2910770896;72106.63946680285;80215;85874;11827.763444731057;12032.639020829389;12097.153199276143;12217.328046957326;12322.383880805924;17417.303307867798;17431.302747890197;17443.476695699148;17451.47645570635;17461.912690436468;8837.204651581389;9145.131212534798;9264.39781414206;9274.536273186313;9283.628654853865;9069.909300907038;9099.226565741854;9115.042920493386;9235.12266334697;9245.075492450702;5174.24973378858;5209.270702101709;5248.343957005374;5437.287254514894;5482.780688772405;2075.1803037799987;2163.448320678217;2171.3594489625284;2172.3157205480225;2197.846150769463;966.6520052780993;974.4056125763278;981.1464026297167;1006.1296978114025;1053.710229686827;19602;21017.474563136046;21251.68122478164;21282.467938301357;21691;1018.7453136715822;1026.573971801694;1035.984460233097;1068.6847380022912;1090.7436752363187;11116.444177791078;11680.824787628193;12029.09781766371;12074.577389791315;12103.455344509519;421574;444604;471222;473320;475175;70133.64933175403;83287;98657;100606;102214.48892755489;1024.3751311699857;1059.2055958031476;1071.8713754349528;1113.3041848844473;1117.251441534174;18721.344752933583;18798.906005469886;18802.341918032802;18987.43037708871;19257;40764.796176020114;41963.7901810489;43107.78446107749;45484.545154549356;46186.538134619565;64041;64812.67593662001;65656;66406;66932.665336673;8956.328275379243;9121.04229055966;9125.087491250822;9329.813403732023;9466.384684995453;10095.29332946716;13669.453221871412;13725.931370343083;13865.237411942036;13881.889448844271;12448.190867593577;12449.190802597803;12571.308578028296;12722.809157862825;13179.736405272033;12024.278543287432;12180.512779488721;12262.20295680778;12395.132340736061;12607.180533265308;12020.879791201976;12028.699282518008;12251.264924104582;12441.191322564004;12645.620631381073;9000.144986226298;9273.350865439354;9343.928482741494;9527.190188833749;9537.952310238405;9425.57584908667;12202.389880506116;12278.07914406441;12300.569480068154;12776.10567260283 01:36:48 INFO - PROCESS | 2617 | |3;mootools.html;1466.5673626280247;1544.9382024719011;1672.4062957650035;1764.8235176482344;1873.325602782998;9067.274618030558;9786;9815.411075335485;9823.214142868572;9823.75440613985;8248;8476.449030812993;9075.95462022689;9342.626294948193;9358.578863951121;2549.502846944842;2572.176903390916;2579.767820896119;2581.0579138614457;2583.9483210335784;2130.1160018592263;2526.7220605733355;2561.244432892301;2563.333533281351;2567.332493551671;2282.315305408379;2443.9144629937955;2484.465839844433;2505.7870081043134;2513.070164039308;2451.840630359021;2467.444824914389;2472.4807790364043;2477.690288713911;2549.68128983877;2500.6374075758913;2511.4474815540552;2511.8744062796786;2515.3963048868322;2532.556802559545;9580.808383832355;9663.082007209305;9695.127438530566;9697.272704547187;9715.562799674031;5594.888102237933;5749.31008279005;5775.48020678141;5780.826575202751;5782.277215348082;3612.151144481045;3618.475321078455;3674.889753307405;3680.190358121209;3680.35593771089;1207.3842340406368;1208.55283545088;1218.439517821803;1223.3516236394726;1228.8525376954733;13271.535496257584;13458.05793594439;13483.393247303895;13505.729885402336;13531.052826302162;4902.950970490321;4957.992013234646;5035.370578677665;5086.0845047891735;5122.641415100908;10534;10542.525586348633;10549.894501055045;10556.313839600401;10570.207234457293;5769;5769.711514424262;5770.105633626795;5770.422957704196;5770.653760774367;5318.414974352818;5328.0675881720545;5376.650517716336;5394.811181608625;5420.07858664028;4028.1540876415693;4064.7561146331313;4099.077707515785;4145.689073319513;4156.688248381383;1130.2427373659668;1229.4836168809122;1230.3110258255406;1234.8579913309904;1244.2472304255975;883.2183517586853;940.6472572785206;992.1368409483651;1023.8617786598862;1045.911097556701;6176.81469555914;6363.300036995926;6468.320826313264;6471.255805582324;6503.057056726748;1009.4801177393648;1033.9379637221791;1044.6030508406757;1060.8779990301214;1076.7361996310954;4275.48694156697;4376.14665140301;4526.932096018562;4546.477155127137;4549.9990002199465;11562.190646654653;12001.939990299992;12005;12022.338771367484;12022.879771202175;11265.662030139109;11699.707507312214;11704.648860534198;11935.104867135;11967.64097077089;8702.347323950602;8914.777130571658;8919.152680495343;8925.152110549487;8929.107089291148;6525.096332132156;6655.800325990228;6678.432333251728;6695.196576410862;6813.352731490699;10942.069924056545;11022.889771102185;11030.558777648805;11129.83305250438;11232.83150752723;11332.206745527734;11402.486888090221;11429.771404572028;11462.541498339973;11619.244749091282;8396.118407567361;8597.140285971353;8645.697400590949;8671.653133874574;8700.347473939606;9197.908020919705;9288.535573221177;9328.253739700944;9336.673216437392;9373.906260937303;6663.333666633298;8206.958965205136;8304.128066552925;8370.832583348178;8496.91503084986;8095.83808323842;8149.79625509367;8250.38122140854;8312.625931833203;8505.532195729294;7747.30274275318;7828.217178282126;7828.765137045898;7828.921710782819;7830.138684744559;5387.757550910297;5481.862953426196;5511.862203444785;5525.917111243415;5547.472990065938;5608.07466767995;5681.715914204356;5746.9712651436475;5758.021136406737;5940.732667029907 01:36:48 INFO - PROCESS | 2617 | |4;prototype.html;350.9804019323864;351.83463772027136;352.97988812332346;353.2017640133298;362.1923111461441;5599.804006859761;5607.27105476288;5631.971840140804;5642.689652069134;5659.292588426447;5496.835094947158;5500.532454741352;5539.667619942807;5547.611667183299;5558.388577256498;3880.417937309412;4145.3367461206135;4309.418228539138;4366.126774645068;4367.366731823884;4356.869293921188;4358.738475691453;4366.8253269869165;4372.169287835317;4381.211381951248;4348.239058164824;4352.934705979413;4374.956250437503;4390.956090439086;4400.691951563392;4121.4848143982;4153.730007549515;4266.679999000056;4312.439382880206;4399.978000110011;3796.215778584061;3803.7147213959065;3810.599887011879;3815.1606646537716;3820.465134881119;5328.653637513549;5346.946530534684;5349.144136938071;5373.865653358658;5403.162509810985;4820.204666230068;4947.713594465465;4979.651424400257;4994.876152865612;5023.497650234984;5691.7723291068305;5709.371969083398;5786.566007549451;5791.768329266824;5797.913031304533;4276.764777937212;4285.086141423862;4301.612854843079;4311.116221174651;4340.8697739067875;5650.208970744099;5826.388229235911;5941.821745347646;5959.970200149015;5991.161237426763;4267.871963841089;4378.496472905656;4392.407025051577;4396.087701849706;4401.053773438662;4437.267850804646;4451.910961780746;4454.420925279757;4481.327800829901;4503.166914120898;4898.289747986593;5007.624428167902;5017.949820501822;5047.192449208109;5062.898742025139;5715.342735585377;5725.312962444534;5734.455226753452;5740.388123403841;5783.277090363705;1495.4691084665005;1524.4892960858015;1525.916599214548;1535.4088675859875;1537;948.3456415073578;985.7289245457413;1000.284796370584;1010.6917390195914;1024.8616436781087;5023.246513023076;5059.507445303644;5064.316317297192;5090.694558326512;5094.286799848024;5280.1519468185825;5286.762095705702;5298.443663415363;5303.806643505258;5322.973385133049;4790.257510085942;5030.84907452777;5031.320771695848;5038.294638750578;5040.84877453677;2777.986110069477;2780.5968134620366;2813.957790633142;2839;2842.6730925943366;5429.484199001167;5488.91766623501;5511.751971161308;5539.556835453154;5574.247476590608;5114.872128196825;5348.331458567679;5381.9461805382225;5382.569394448435;5405.594510442816;5378.623496355296;5387.4073851876265;5422.945770542244;5443.42844001382;5447.836564903059;4896.657233993728;5236.240745092016;5264.630916087853;5283.463937310036;5293.179557168645;5111.670965549059;5122.41205226381;5130.178786530728;5168.664036837594;5181.896362072813;5361.874006458687;5842.364138041356;6000.279966404059;6026.668533230714;6102.938970610237;4364.498082720528;4374.978125109354;4389.692721509591;4390.143921935192;4421.182081314967;5799.159121927381;5996.130561068533;6008.218931538872;6035.486983606267;6068.4841788448475;5841.853953651193;5857.384974577608;5858.912116318344;5863.677497737665;5864.032434648235;5798.82603521895;5851.21008663842;5855.2973643163095;5861.794837180849;5865.912011319749;5531.197976293495;5768.913466297925;5787;5795.217645617956;5806.564507662027;5857.355690873916;5871.412858714094;5880.882382352414;5881.500072493886;5888.793892213752;5491.780328786804;5740.225069615549;5747.942520574741;5766.971165144148;5770.538356931519 01:36:48 INFO - PROCESS | 2617 | |5;yui.html;802.4423025996934;838.7903024243939;871.8997315308742;890;931.1852129386784;16993.2353044113;17014.659706805858;17052.658946821055;17075.487735367944;17097.40159094432;16048.75926861098;16255.918720406411;16274.186290685477;16293.51119466415;16305.021698698087;730.755197009;745.526590614958;873.6418068591879;881.171698603314;894.2711689972684;886.1492966751925;888.8933328000649;889.1508609278156;889.6352495476856;890.0254221627308;931.1805611062256;931.9720408387759;932.7201839448138;932.7574830544039;933.7058826469674;764.8470305938806;789.7591234673422;792.2196636313226;794.0986979777936;920.3419555018161;663.515633587479;683.8871586188272;685.0204207982583;687.4603436302529;688.4595592459945;15883.047017178891;15982;16019.11894845783;16044;16053.598660033476;15177;15296.235188240544;15404.614884627861;15443.536693899201;15518.922405388013;13166.73666526664;13497.662558436019;13514.864851351458;13893.58319250414;13984.650383740387;4491.595756381941;4622.930656040128;4665.183592871234;4758.191107511697;4763;76734;76827.61586192089;81768.59115704383;86896.13103868943;89036.55481722615;10856.945715271373;11167.106631469464;11217.326960382403;11246.381449020382;11405.828912566318;23150;23163.305100846665;23182.884085579462;23234;23241.76758232396;8636.870446943301;8684.305255579538;8879;9027.277817774562;9176.6788162414;4699.8320426878545;4873.098476781806;4999.925001124986;5096.464871188546;5158.0199762045095;3465.2203254268093;3560.732945029133;3568.2506673598314;3586.7668601540818;3591.5510561179854;646.2342124582385;650.4048795352294;661.3056290894541;662.413763819014;662.6454846657016;685.348918527401;691.837418206721;702.1714377035148;702.7751119641767;727.4798519058899;9641.325107242566;9683.370580912218;9730.72154723531;9801.705948821547;9855.45794981283;627.9560430769893;686.0361192524512;692.1659400422479;696.084648686975;696.3732640623379;4612.76936153191;4760.262159365235;4781.330613714083;4812.614990800727;4827.831025914076;76154.61922690351;109996.35005474924;129898.35050824685;133002;138607;90385;90716.5464172675;91757;113780;116921.41539292419;72511.63744181245;90902;91019.54490227507;91240.54379728192;94200.52899735594;8903.643854246016;9124.908750912671;9316.813663726824;9346.299027573095;9747.561359738971;56151.71924140353;57484.42515574791;58392;58817;59421.086287518694;82392;83938.58030709808;84936;85388.57305713432;87034;12348.38258087074;12659.746805064031;12706.555270565486;12937.741245174855;12976.675583110498;15028.398864045315;15054.774178387552;15151.545453636409;15299.311530980909;15662.216889155725;13592.320383980563;13952.372143253375;14283.428662853785;14304.856951430353;14746.557603271918;13760.105593136414;14027.859721403063;14029.929850350683;14106.506272280421;14304.213268270345;13859.584212473643;14285.142891426547;14403.711925761636;14417.062890912057;14448.205348705922;11835.28988260707;11937.582184623496;12359.75280494403;12606.306653134166;12706.110572259853;11295;11577.884221157681;11919.225250358699;12231.12568546373;12261.26432414058 01:36:48 INFO - PROCESS | 2617 | __end_tp_report 01:36:48 INFO - PROCESS | 2617 | __start_cc_report 01:36:48 INFO - PROCESS | 2617 | _x_x_mozilla_cycle_collect,862 01:36:48 INFO - PROCESS | 2617 | __end_cc_report 01:36:48 INFO - PROCESS | 2617 | __startTimestamp1474360608902__endTimestamp 01:36:48 INFO - PROCESS | 2617 | 01:36:48 INFO - PROCESS | 2617 | ------- Summary: start ------- 01:36:48 INFO - PROCESS | 2617 | Number of tests: 6 01:36:48 INFO - PROCESS | 2617 | 01:36:48 INFO - PROCESS | 2617 | [#0] dojo.html Cycles:165 Average:5663.17 Median:5899.97 stddev:666.44 (11.3%) stddev-sans-first:637.19 01:36:48 INFO - PROCESS | 2617 | Values: 3090.4 3126.5 3131.7 3150.1 3167.0 5589.8 5668.4 5669.0 5672.6 5697.9 5678.5 5725.7 5748.9 5753.6 5759.8 5110.3 5145.3 5159.9 5174.0 5193.8 5045.2 5297.2 5298.3 5309.7 5311.9 5032.4 5166.8 5171.5 5187.9 5202.0 5204.9 5224.2 5252.9 5317.7 5318.7 5142.5 5190.6 5259.4 5268.5 5293.9 5555.1 5560.7 5589.7 5601.0 5635.3 5953.2 5966.8 6117.7 6119.8 6121.7 6119.7 6152.4 6158.8 6170.3 6173.1 5478.7 5527.6 5630.5 5672.8 5674.5 6243.0 6250.1 6287.7 6292.5 6318.1 6056.9 6070.4 6077.6 6078.2 6106.9 5900.0 5948.3 5975.4 6033.5 6047.8 6068.4 6071.8 6073.7 6074.8 6108.4 6023.2 6044.4 6068.1 6078.1 6104.8 4522.8 4582.7 4597.9 4633.9 4645.4 4133.9 4135.6 4150.1 4150.8 4159.0 5862.0 5866.4 5867.1 5867.4 5867.4 5913.6 5966.4 6006.6 6017.2 6166.4 5764.3 5838.6 5840.6 5864.9 5880.4 5284.5 5285.5 5289.5 5296.0 5299.4 6116.4 6133.4 6276.3 6327.8 6333.0 6043.0 6128.9 6137.8 6153.8 6201.2 6347.3 6359.9 6361.1 6377.8 6381.5 5762.8 6062.3 6068.8 6070.7 6073.4 6000.1 6067.6 6127.3 6136.6 6197.2 5984.3 6034.8 6037.2 6044.5 6067.8 6027.1 6041.7 6042.8 6044.9 6047.8 5672.6 5780.7 5877.4 5917.3 5920.5 6044.7 6053.4 6062.9 6063.7 6088.5 5689.1 5884.3 5898.3 5900.1 5903.0 01:36:48 INFO - PROCESS | 2617 | 01:36:48 INFO - PROCESS | 2617 | [#1] ext.html Cycles:180 Average:64984.98 Median:42249.58 stddev:129435.54 (306.4%) stddev-sans-first:129708.24 01:36:48 INFO - PROCESS | 2617 | Values: 555.8 576.5 594.8 601.8 615.1 34485.5 37532.6 37541.2 37552.6 37573.1 47946.3 48911.5 48933.0 52587.0 52714.2 9168.0 9298.1 9298.6 9302.5 9306.8 8835.2 9218.4 9310.2 9316.5 9316.8 8949.3 11023.8 11286.7 11292.4 11751.2 11304.9 11327.0 11391.2 11409.7 11413.5 8467.5 10920.6 10975.0 10978.1 10990.8 33390.0 35866.8 35922.0 43265.0 43767.8 3499.7 3657.5 3747.0 3857.6 4302.8 33635.0 34107.1 34228.7 34534.1 35869.8 4733.5 4817.9 4842.1 4842.9 4865.1 390033.0 391802.0 391822.0 392159.0 398576.0 56653.0 56744.4 56788.0 56789.4 56839.7 59091.0 60466.7 65239.3 66173.0 66422.3 17951.2 18026.0 18057.4 18066.6 18075.6 14870.9 14972.6 14988.9 14991.0 14994.7 15751.0 16132.5 17078.7 17357.4 17493.7 6314.8 6470.6 6498.2 6626.6 6662.0 1006.7 1060.4 1076.9 1140.3 1145.2 32774.2 32896.5 32902.2 32938.2 33057.7 1128.2 1150.5 1153.2 1195.2 1201.6 17221.0 17604.0 17731.6 17771.2 17805.7 565109.0 571215.0 571859.0 572127.0 572695.0 61125.4 61223.7 61247.0 61283.7 61306.7 1369.2 1389.9 1402.0 1414.9 1419.9 31398.0 32372.5 32828.2 33036.5 33762.7 53733.0 54092.5 54171.5 54196.5 54241.7 481909.0 483278.0 483992.0 486155.0 488273.0 43382.8 43861.8 45318.3 45409.3 45455.0 50501.0 50739.7 50785.2 51159.0 52356.2 42334.6 42733.0 43380.0 44031.3 45070.8 42321.8 42348.4 42484.8 43361.8 44297.1 42177.4 42995.4 43207.1 43253.1 43343.0 35737.5 37571.4 38429.0 38524.0 38547.0 28510.4 28984.0 29065.3 29300.4 29426.4 01:36:48 INFO - PROCESS | 2617 | 01:36:48 INFO - PROCESS | 2617 | [#2] jquery.html Cycles:180 Average:28548.90 Median:2195.98 stddev:75580.23 (3441.8%) stddev-sans-first:75762.71 01:36:48 INFO - PROCESS | 2617 | Values: 403.7 408.2 412.5 421.6 434.5 14273.7 16241.8 19930.5 20485.1 20834.4 20308.5 20379.1 20970.1 20995.6 21184.0 5649.5 5820.4 5923.8 5959.7 5975.3 4073.7 4273.3 5954.9 5958.4 6001.0 4302.9 4534.5 4561.2 6599.8 6670.0 5841.8 6644.6 6645.2 6652.3 6666.5 5923.5 6021.7 6028.3 6030.1 6042.5 15476.2 19030.4 20051.7 24317.6 24424.3 2194.1 2324.7 2431.3 2571.5 3031.3 10735.8 10862.6 11058.4 11061.9 11316.0 1849.5 1856.9 1863.1 1900.6 1932.6 67077.0 70892.3 72106.6 80215.0 85874.0 11827.8 12032.6 12097.2 12217.3 12322.4 17417.3 17431.3 17443.5 17451.5 17461.9 8837.2 9145.1 9264.4 9274.5 9283.6 9069.9 9099.2 9115.0 9235.1 9245.1 5174.2 5209.3 5248.3 5437.3 5482.8 2075.2 2163.4 2171.4 2172.3 2197.8 966.7 974.4 981.1 1006.1 1053.7 19602.0 21017.5 21251.7 21282.5 21691.0 1018.7 1026.6 1036.0 1068.7 1090.7 11116.4 11680.8 12029.1 12074.6 12103.5 421574.0 444604.0 471222.0 473320.0 475175.0 70133.6 83287.0 98657.0 100606.0 102214.5 1024.4 1059.2 1071.9 1113.3 1117.3 18721.3 18798.9 18802.3 18987.4 19257.0 40764.8 41963.8 43107.8 45484.5 46186.5 64041.0 64812.7 65656.0 66406.0 66932.7 8956.3 9121.0 9125.1 9329.8 9466.4 10095.3 13669.5 13725.9 13865.2 13881.9 12448.2 12449.2 12571.3 12722.8 13179.7 12024.3 12180.5 12262.2 12395.1 12607.2 12020.9 12028.7 12251.3 12441.2 12645.6 9000.1 9273.4 9343.9 9527.2 9538.0 9425.6 12202.4 12278.1 12300.6 12776.1 01:36:48 INFO - PROCESS | 2617 | 01:36:48 INFO - PROCESS | 2617 | [#3] mootools.html Cycles:180 Average:6285.48 Median:4548.24 stddev:3587.85 (78.9%) stddev-sans-first:3579.63 01:36:48 INFO - PROCESS | 2617 | Values: 1466.6 1544.9 1672.4 1764.8 1873.3 9067.3 9786.0 9815.4 9823.2 9823.8 8248.0 8476.4 9076.0 9342.6 9358.6 2549.5 2572.2 2579.8 2581.1 2583.9 2130.1 2526.7 2561.2 2563.3 2567.3 2282.3 2443.9 2484.5 2505.8 2513.1 2451.8 2467.4 2472.5 2477.7 2549.7 2500.6 2511.4 2511.9 2515.4 2532.6 9580.8 9663.1 9695.1 9697.3 9715.6 5594.9 5749.3 5775.5 5780.8 5782.3 3612.2 3618.5 3674.9 3680.2 3680.4 1207.4 1208.6 1218.4 1223.4 1228.9 13271.5 13458.1 13483.4 13505.7 13531.1 4903.0 4958.0 5035.4 5086.1 5122.6 10534.0 10542.5 10549.9 10556.3 10570.2 5769.0 5769.7 5770.1 5770.4 5770.7 5318.4 5328.1 5376.7 5394.8 5420.1 4028.2 4064.8 4099.1 4145.7 4156.7 1130.2 1229.5 1230.3 1234.9 1244.2 883.2 940.6 992.1 1023.9 1045.9 6176.8 6363.3 6468.3 6471.3 6503.1 1009.5 1033.9 1044.6 1060.9 1076.7 4275.5 4376.1 4526.9 4546.5 4550.0 11562.2 12001.9 12005.0 12022.3 12022.9 11265.7 11699.7 11704.6 11935.1 11967.6 8702.3 8914.8 8919.2 8925.2 8929.1 6525.1 6655.8 6678.4 6695.2 6813.4 10942.1 11022.9 11030.6 11129.8 11232.8 11332.2 11402.5 11429.8 11462.5 11619.2 8396.1 8597.1 8645.7 8671.7 8700.3 9197.9 9288.5 9328.3 9336.7 9373.9 6663.3 8207.0 8304.1 8370.8 8496.9 8095.8 8149.8 8250.4 8312.6 8505.5 7747.3 7828.2 7828.8 7828.9 7830.1 5387.8 5481.9 5511.9 5525.9 5547.5 5608.1 5681.7 5747.0 5758.0 5940.7 01:36:48 INFO - PROCESS | 2617 | 01:36:48 INFO - PROCESS | 2617 | [#4] prototype.html Cycles:180 Average:4746.59 Median:5250.44 stddev:1354.85 (25.8%) stddev-sans-first:1317.87 01:36:48 INFO - PROCESS | 2617 | Values: 351.0 351.8 353.0 353.2 362.2 5599.8 5607.3 5632.0 5642.7 5659.3 5496.8 5500.5 5539.7 5547.6 5558.4 3880.4 4145.3 4309.4 4366.1 4367.4 4356.9 4358.7 4366.8 4372.2 4381.2 4348.2 4352.9 4375.0 4391.0 4400.7 4121.5 4153.7 4266.7 4312.4 4400.0 3796.2 3803.7 3810.6 3815.2 3820.5 5328.7 5346.9 5349.1 5373.9 5403.2 4820.2 4947.7 4979.7 4994.9 5023.5 5691.8 5709.4 5786.6 5791.8 5797.9 4276.8 4285.1 4301.6 4311.1 4340.9 5650.2 5826.4 5941.8 5960.0 5991.2 4267.9 4378.5 4392.4 4396.1 4401.1 4437.3 4451.9 4454.4 4481.3 4503.2 4898.3 5007.6 5017.9 5047.2 5062.9 5715.3 5725.3 5734.5 5740.4 5783.3 1495.5 1524.5 1525.9 1535.4 1537.0 948.3 985.7 1000.3 1010.7 1024.9 5023.2 5059.5 5064.3 5090.7 5094.3 5280.2 5286.8 5298.4 5303.8 5323.0 4790.3 5030.8 5031.3 5038.3 5040.8 2778.0 2780.6 2814.0 2839.0 2842.7 5429.5 5488.9 5511.8 5539.6 5574.2 5114.9 5348.3 5381.9 5382.6 5405.6 5378.6 5387.4 5422.9 5443.4 5447.8 4896.7 5236.2 5264.6 5283.5 5293.2 5111.7 5122.4 5130.2 5168.7 5181.9 5361.9 5842.4 6000.3 6026.7 6102.9 4364.5 4375.0 4389.7 4390.1 4421.2 5799.2 5996.1 6008.2 6035.5 6068.5 5841.9 5857.4 5858.9 5863.7 5864.0 5798.8 5851.2 5855.3 5861.8 5865.9 5531.2 5768.9 5787.0 5795.2 5806.6 5857.4 5871.4 5880.9 5881.5 5888.8 5491.8 5740.2 5747.9 5767.0 5770.5 01:36:48 INFO - PROCESS | 2617 | 01:36:48 INFO - PROCESS | 2617 | [#5] yui.html Cycles:180 Average:21983.45 Median:4820.22 stddev:31667.29 (657.0%) stddev-sans-first:31716.18 01:36:48 INFO - PROCESS | 2617 | Values: 802.4 838.8 871.9 890.0 931.2 16993.2 17014.7 17052.7 17075.5 17097.4 16048.8 16255.9 16274.2 16293.5 16305.0 730.8 745.5 873.6 881.2 894.3 886.1 888.9 889.2 889.6 890.0 931.2 932.0 932.7 932.8 933.7 764.8 789.8 792.2 794.1 920.3 663.5 683.9 685.0 687.5 688.5 15883.0 15982.0 16019.1 16044.0 16053.6 15177.0 15296.2 15404.6 15443.5 15518.9 13166.7 13497.7 13514.9 13893.6 13984.7 4491.6 4622.9 4665.2 4758.2 4763.0 76734.0 76827.6 81768.6 86896.1 89036.6 10856.9 11167.1 11217.3 11246.4 11405.8 23150.0 23163.3 23182.9 23234.0 23241.8 8636.9 8684.3 8879.0 9027.3 9176.7 4699.8 4873.1 4999.9 5096.5 5158.0 3465.2 3560.7 3568.3 3586.8 3591.6 646.2 650.4 661.3 662.4 662.6 685.3 691.8 702.2 702.8 727.5 9641.3 9683.4 9730.7 9801.7 9855.5 628.0 686.0 692.2 696.1 696.4 4612.8 4760.3 4781.3 4812.6 4827.8 76154.6 109996.4 129898.4 133002.0 138607.0 90385.0 90716.5 91757.0 113780.0 116921.4 72511.6 90902.0 91019.5 91240.5 94200.5 8903.6 9124.9 9316.8 9346.3 9747.6 56151.7 57484.4 58392.0 58817.0 59421.1 82392.0 83938.6 84936.0 85388.6 87034.0 12348.4 12659.7 12706.6 12937.7 12976.7 15028.4 15054.8 15151.5 15299.3 15662.2 13592.3 13952.4 14283.4 14304.9 14746.6 13760.1 14027.9 14029.9 14106.5 14304.2 13859.6 14285.1 14403.7 14417.1 14448.2 11835.3 11937.6 12359.8 12606.3 12706.1 11295.0 11577.9 11919.2 12231.1 12261.3 01:36:48 INFO - PROCESS | 2617 | -------- Summary: end -------- 01:36:48 INFO - PROCESS | 2617 | 01:36:49 INFO - TEST-INFO | 2617: exit 0 01:36:49 INFO - TEST-OK | dromaeo_css | took 1114188ms 01:36:49 INFO - TEST-START | kraken 01:36:49 INFO - operating with platform_type : linux_ 01:36:49 INFO - Initialising browser for kraken test... 01:36:49 INFO - TEST-INFO | started process 3042 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp55erQ5/profile http://localhost:59197/getInfo.html) 01:36:53 INFO - PROCESS | 3042 | __metrics Screen width/height:1600/1200 01:36:53 INFO - PROCESS | 3042 | colorDepth:24 01:36:53 INFO - PROCESS | 3042 | Browser inner width/height: 1024/697 01:36:53 INFO - PROCESS | 3042 | __metrics 01:36:54 INFO - PROCESS | 3042 | 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" 01:36:54 INFO - PROCESS | 3042 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:36:54 INFO - PROCESS | 3042 | console.error: PushService: 01:36:54 INFO - PROCESS | 3042 | stateChangeProcessEnqueue: Error transitioning state 01:36:54 INFO - PROCESS | 3042 | UnknownError 01:36:55 INFO - TEST-INFO | 3042: exit 0 01:36:55 INFO - Browser initialized. 01:36:55 INFO - Running cycle 1/1 for kraken test... 01:36:55 INFO - TEST-INFO | started process 3134 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp55erQ5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:37:06 INFO - PROCESS | 3134 | RSS: Main: 147746816 01:37:06 INFO - PROCESS | 3134 | 01:37:09 INFO - PROCESS | 3134 | Cycle 1(1): loaded http://localhost:59197/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59197/tests/kraken/driver.html?testName=audio-beat-detection) 01:37:10 INFO - PROCESS | 3134 | RSS: Main: 189243392 01:37:10 INFO - PROCESS | 3134 | 01:37:12 INFO - PROCESS | 3134 | Cycle 1(1): loaded http://localhost:59197/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59197/tests/kraken/driver.html?testName=audio-dft) 01:37:13 INFO - PROCESS | 3134 | RSS: Main: 178081792 01:37:13 INFO - PROCESS | 3134 | 01:37:16 INFO - PROCESS | 3134 | Cycle 1(1): loaded http://localhost:59197/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59197/tests/kraken/driver.html?testName=audio-fft) 01:37:16 INFO - PROCESS | 3134 | RSS: Main: 269570048 01:37:16 INFO - PROCESS | 3134 | 01:37:18 INFO - PROCESS | 3134 | Cycle 1(1): loaded http://localhost:59197/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59197/tests/kraken/driver.html?testName=audio-oscillator) 01:37:19 INFO - PROCESS | 3134 | RSS: Main: 175968256 01:37:19 INFO - PROCESS | 3134 | 01:37:21 INFO - PROCESS | 3134 | Cycle 1(1): loaded http://localhost:59197/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59197/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:37:22 INFO - PROCESS | 3134 | RSS: Main: 176713728 01:37:22 INFO - PROCESS | 3134 | 01:37:25 INFO - PROCESS | 3134 | Cycle 1(1): loaded http://localhost:59197/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59197/tests/kraken/driver.html?testName=imaging-darkroom) 01:37:25 INFO - PROCESS | 3134 | RSS: Main: 213434368 01:37:25 INFO - PROCESS | 3134 | 01:37:29 INFO - PROCESS | 3134 | Cycle 1(1): loaded http://localhost:59197/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59197/tests/kraken/driver.html?testName=imaging-desaturate) 01:37:29 INFO - PROCESS | 3134 | RSS: Main: 212697088 01:37:29 INFO - PROCESS | 3134 | 01:37:32 INFO - PROCESS | 3134 | Cycle 1(1): loaded http://localhost:59197/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59197/tests/kraken/driver.html?testName=json-parse-financial) 01:37:33 INFO - PROCESS | 3134 | RSS: Main: 212688896 01:37:33 INFO - PROCESS | 3134 | 01:37:34 INFO - PROCESS | 3134 | Cycle 1(1): loaded http://localhost:59197/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59197/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:37:35 INFO - PROCESS | 3134 | RSS: Main: 174690304 01:37:35 INFO - PROCESS | 3134 | 01:37:36 INFO - PROCESS | 3134 | Cycle 1(1): loaded http://localhost:59197/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59197/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:37:37 INFO - PROCESS | 3134 | RSS: Main: 181649408 01:37:37 INFO - PROCESS | 3134 | 01:37:39 INFO - PROCESS | 3134 | Cycle 1(1): loaded http://localhost:59197/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59197/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:37:40 INFO - PROCESS | 3134 | RSS: Main: 184250368 01:37:40 INFO - PROCESS | 3134 | 01:37:42 INFO - PROCESS | 3134 | Cycle 1(1): loaded http://localhost:59197/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59197/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:37:43 INFO - PROCESS | 3134 | RSS: Main: 184668160 01:37:43 INFO - PROCESS | 3134 | 01:37:45 INFO - PROCESS | 3134 | Cycle 1(1): loaded http://localhost:59197/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59197/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:37:46 INFO - PROCESS | 3134 | RSS: Main: 182841344 01:37:46 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | Cycle 1(1): loaded http://localhost:59197/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59197/tests/kraken/driver.html?testName=ai-astar) 01:37:48 INFO - PROCESS | 3134 | RSS: Main: 233783296 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | __start_tp_report 01:37:48 INFO - PROCESS | 3134 | _x_x_mozilla_page_load 01:37:48 INFO - PROCESS | 3134 | _x_x_mozilla_page_load_details 01:37:48 INFO - PROCESS | 3134 | |i|pagename|runs| 01:37:48 INFO - PROCESS | 3134 | |0;ai-astar;108;112;110;110;111;111;109;110;111;110 01:37:48 INFO - PROCESS | 3134 | |1;audio-beat-detection;145;116;123;158;123;123;116;120;150;120 01:37:48 INFO - PROCESS | 3134 | |2;audio-dft;161;160;159;161;161;161;160;161;161;162 01:37:48 INFO - PROCESS | 3134 | |3;audio-fft;76;74;75;105;76;76;75;75;98;75 01:37:48 INFO - PROCESS | 3134 | |4;audio-oscillator;83;81;109;82;81;82;99;82;82;103 01:37:48 INFO - PROCESS | 3134 | |5;imaging-gaussian-blur;116;117;117;119;117;117;117;117;117;117 01:37:48 INFO - PROCESS | 3134 | |6;imaging-darkroom;146;146;145;147;148;147;146;146;146;147 01:37:48 INFO - PROCESS | 3134 | |7;imaging-desaturate;95;94;96;94;94;95;95;95;95;94 01:37:48 INFO - PROCESS | 3134 | |8;json-parse-financial;49;47;47;47;51;48;49;47;47;48 01:37:48 INFO - PROCESS | 3134 | |9;json-stringify-tinderbox;60;60;60;60;60;60;60;60;60;60 01:37:48 INFO - PROCESS | 3134 | |10;stanford-crypto-aes;74;75;79;74;74;73;73;73;78;77 01:37:48 INFO - PROCESS | 3134 | |11;stanford-crypto-ccm;127;133;118;127;115;127;118;118;164;119 01:37:48 INFO - PROCESS | 3134 | |12;stanford-crypto-pbkdf2;158;154;153;151;151;157;150;149;155;157 01:37:48 INFO - PROCESS | 3134 | |13;stanford-crypto-sha256-iterative;62;63;61;61;61;62;62;63;64;61 01:37:48 INFO - PROCESS | 3134 | __end_tp_report 01:37:48 INFO - PROCESS | 3134 | __start_cc_report 01:37:48 INFO - PROCESS | 3134 | _x_x_mozilla_cycle_collect,1569 01:37:48 INFO - PROCESS | 3134 | __end_cc_report 01:37:48 INFO - PROCESS | 3134 | __startTimestamp1474360668288__endTimestamp 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | ------- Summary: start ------- 01:37:48 INFO - PROCESS | 3134 | Number of tests: 14 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | [#0] ai-astar Cycles:10 Average:110.20 Median:110.50 stddev:1.14 (1.0%) stddev-sans-first:0.88 01:37:48 INFO - PROCESS | 3134 | Values: 108.0 112.0 110.0 110.0 111.0 111.0 109.0 110.0 111.0 110.0 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:123.00 stddev:15.44 (12.5%) stddev-sans-first:15.31 01:37:48 INFO - PROCESS | 3134 | Values: 145.0 116.0 123.0 158.0 123.0 123.0 116.0 120.0 150.0 120.0 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 01:37:48 INFO - PROCESS | 3134 | Values: 161.0 160.0 159.0 161.0 161.0 161.0 160.0 161.0 161.0 162.0 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | [#3] audio-fft Cycles:10 Average:80.50 Median:75.50 stddev:11.21 (14.8%) stddev-sans-first:11.77 01:37:48 INFO - PROCESS | 3134 | Values: 76.0 74.0 75.0 105.0 76.0 76.0 75.0 75.0 98.0 75.0 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:82.00 stddev:10.81 (13.2%) stddev-sans-first:11.29 01:37:48 INFO - PROCESS | 3134 | Values: 83.0 81.0 109.0 82.0 81.0 82.0 99.0 82.0 82.0 103.0 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.67 01:37:48 INFO - PROCESS | 3134 | Values: 116.0 117.0 117.0 119.0 117.0 117.0 117.0 117.0 117.0 117.0 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.84 (0.6%) stddev-sans-first:0.88 01:37:48 INFO - PROCESS | 3134 | Values: 146.0 146.0 145.0 147.0 148.0 147.0 146.0 146.0 146.0 147.0 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 01:37:48 INFO - PROCESS | 3134 | Values: 95.0 94.0 96.0 94.0 94.0 95.0 95.0 95.0 95.0 94.0 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | [#8] json-parse-financial Cycles:10 Average:48.00 Median:48.00 stddev:1.33 (2.8%) stddev-sans-first:1.36 01:37:48 INFO - PROCESS | 3134 | Values: 49.0 47.0 47.0 47.0 51.0 48.0 49.0 47.0 47.0 48.0 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 01:37:48 INFO - PROCESS | 3134 | Values: 60.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:74.50 stddev:2.21 (3.0%) stddev-sans-first:2.32 01:37:48 INFO - PROCESS | 3134 | Values: 74.0 75.0 79.0 74.0 74.0 73.0 73.0 73.0 78.0 77.0 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | [#11] stanford-crypto-ccm Cycles:10 Average:126.60 Median:127.00 stddev:14.35 (11.3%) stddev-sans-first:15.22 01:37:48 INFO - PROCESS | 3134 | Values: 127.0 133.0 118.0 127.0 115.0 127.0 118.0 118.0 164.0 119.0 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.50 Median:154.50 stddev:3.21 (2.1%) stddev-sans-first:2.96 01:37:48 INFO - PROCESS | 3134 | Values: 158.0 154.0 153.0 151.0 151.0 157.0 150.0 149.0 155.0 157.0 01:37:48 INFO - PROCESS | 3134 | 01:37:48 INFO - PROCESS | 3134 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.00 Median:62.00 stddev:1.05 (1.7%) stddev-sans-first:1.12 01:37:48 INFO - PROCESS | 3134 | Values: 62.0 63.0 61.0 61.0 61.0 62.0 62.0 63.0 64.0 61.0 01:37:48 INFO - PROCESS | 3134 | -------- Summary: end -------- 01:37:48 INFO - PROCESS | 3134 | 01:37:49 INFO - TEST-INFO | 3134: exit 0 01:37:49 INFO - TEST-OK | kraken | took 59496ms 01:37:49 INFO - SUITE-END | took 1173s 01:37:49 INFO - Completed test suite (00:19:33) 01:37:49 INFO - javascript benchmark 01:37:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3090.3664748726515, 3126.5310203469476, 3131.7494600431983, 3150.1494596458942, 3166.968330316696, 5589.832305030851, 5668.433156684329, 5669.036263835148, 5672.631278966865, 5697.943020569798, 5678.545716342693, 5725.742341594628, 5748.856278593027, 5753.626014309078, 5759.827205183851, 5110.28456016158, 5145.3311069561, 5159.948400516003, 5174.0, 5193.818216362428, 5045.21799121135, 5297.231901374298, 5298.337707786527, 5309.681419114846, 5311.867203319909, 5032.446430892599, 5166.844994650167, 5171.456997015315, 5187.896242075156, 5202.0, 5204.921926171091, 5224.242484839696, 5252.8686782830355, 5317.680939143664, 5318.654287471302, 5142.485751424865, 5190.6107041972, 5259.447757985395, 5268.473152684739, 5293.947060529423, 5555.138953462179, 5560.749766260487, 5589.664620122765, 5601.0, 5635.295588051494, 5953.226080609536, 5966.791162309341, 6117.724702388403, 6119.816405507842, 6121.724522396503, 6119.694015299262, 6152.384761523812, 6158.753649854, 6170.2904166021035, 6173.135760993465, 5478.671279723229, 5527.585431092644, 5630.5214056805635, 5672.829815105553, 5674.460926212003, 6242.968785156045, 6250.062490626442, 6287.748490060438, 6292.4966002719675, 6318.115463835067, 6056.909146362808, 6070.3929607038945, 6077.64777313826, 6078.240219972504, 6106.877862442726, 5899.970500147472, 5948.315943666447, 5975.372585878507, 6033.5474839387225, 6047.758089676452, 6068.423499767515, 6071.757129714851, 6073.696315184311, 6074.757009719562, 6108.35862234459, 6023.247094113236, 6044.365341639153, 6068.120122582201, 6078.1186727924205, 6104.755809767559, 4522.796474158671, 4582.679212455096, 4597.931031034537, 4633.860984170413, 4645.419322584677, 4133.855315063958, 4135.58644135584, 4150.06623509714, 4150.750954942713, 4158.958410415918, 5862.0, 5866.413358664099, 5867.090600956859, 5867.354590994987, 5867.3839246879315, 5913.556483263773, 5966.373530779292, 6006.639601623917, 6017.157597936292, 6166.383361663798, 5764.279465066867, 5838.562107841929, 5840.649561026352, 5864.941350586525, 5880.441358070976, 5284.524392804666, 5285.524302812765, 5289.523942845162, 5295.973520132452, 5299.364076310791, 6116.35778243278, 6133.395871484143, 6276.278228003746, 6327.841803954938, 6333.0, 6043.0, 6128.877422451436, 6137.815865524041, 6153.815385538441, 6201.193844800147, 6347.333529979285, 6359.904601431075, 6361.077643741724, 6377.840553986188, 6381.521385895983, 5762.7694892203845, 6062.272527296753, 6068.817935461943, 6070.666113363631, 6073.423024812636, 6000.069989151689, 6067.635941843503, 6127.264728232462, 6136.6011209271255, 6197.194364732556, 5984.251968503937, 6034.818955431344, 6037.215162029084, 6044.455998960115, 6067.757289708362, 6027.126066720213, 6041.697915104315, 6042.758289668364, 6044.879102418015, 6047.758089676364, 5672.574556908331, 5780.653160810365, 5877.4122587742595, 5917.289925209003, 5920.526357891272, 6044.727987240497, 6053.424924632153, 6062.878742425215, 6063.72713227897, 6088.543359248163, 5689.061304884648, 5884.264466941632, 5898.292204935436, 5900.085486749561, 5903.0], "value": 5615.367611662559, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.7626167077907, 576.5474102829279, 594.7947957954505, 601.7803501721871, 615.1295916278466, 34485.48271775922, 37532.62467375326, 37541.249175016485, 37552.55908365958, 37573.06067348321, 47946.280805787945, 48911.51088489114, 48933.0, 52587.0, 52714.20928686073, 9167.954160229207, 9298.116678915527, 9298.62805487783, 9302.534873256343, 9306.813863722722, 8835.249003834682, 9218.35471516994, 9310.208632266265, 9316.534173291342, 9316.767080822965, 8949.328800339968, 11023.779524409507, 11286.717832054183, 11292.435378231097, 11751.236169648988, 11304.943475282653, 11326.999999999958, 11391.202615816896, 11409.714757131138, 11413.486393112289, 8467.49195048299, 10920.563177472892, 10975.0, 10978.12175025996, 10990.835137472945, 33390.0, 35866.82066589676, 35922.0, 43265.0, 43767.78116109431, 3499.720022398202, 3657.524521812147, 3746.981265093657, 3857.614238576148, 4302.806373713191, 33635.0, 34107.147321316916, 34228.65771342305, 34534.136646583785, 35869.820650896836, 4733.526647335273, 4817.879553011167, 4842.080004799077, 4842.903141937177, 4865.099956508057, 390033.0, 391802.0, 391822.0, 392159.0, 398576.0, 56653.0, 56744.43255567474, 56788.0, 56789.43210567924, 56839.71580142155, 59091.0, 60466.697666512264, 65239.34760652428, 66173.0, 66422.33577664258, 17951.192196350934, 18026.0, 18057.367992120475, 18066.638667226583, 18075.638487230182, 14870.925645371704, 14972.625684357978, 14988.925055374872, 14991.025583337048, 14994.700105998038, 15751.0, 16132.516024519284, 17078.658426831393, 17357.392491263, 17493.65012699739, 6314.778982735674, 6470.64411457365, 6498.155239818888, 6626.602403855784, 6661.966690166615, 1006.6677996261217, 1060.390275591538, 1076.8546246256656, 1140.2588317593631, 1145.169751929844, 32774.180645484055, 32896.52596189765, 32902.177445563575, 32938.17654558607, 33057.66942330594, 1128.1707944660668, 1150.4995327032768, 1153.1870031627682, 1195.1574140231116, 1201.6034708546333, 17221.0, 17604.0, 17731.645367092588, 17771.20029598645, 17805.7329140063, 565109.0, 571215.0, 571859.0, 572127.0, 572695.0, 61125.38874611197, 61223.69388153031, 61247.0, 61283.69358153181, 61306.69346653238, 1369.1511263016994, 1389.8679625435568, 1401.9929900350637, 1414.8514405987428, 1419.9148051116965, 31398.0, 32372.514412284305, 32828.1792955178, 33036.50445243275, 33762.662373375955, 53733.0, 54092.45907540874, 54171.458285416644, 54196.45803542072, 54241.72879135579, 481909.0, 483278.0, 483992.0, 486155.0, 488273.0, 43382.78308608563, 43861.78069109634, 45318.32022519599, 45409.31886021646, 45455.0, 50501.0, 50739.74630126826, 50785.23822142597, 51159.0, 52356.21465678094, 42334.57665423306, 42733.0, 43380.0, 44031.339529906436, 45070.774646126556, 42321.788391057846, 42348.36477452779, 42484.78757606192, 43361.78319108384, 44297.11405771931, 42177.36733948932, 42995.35506967335, 43207.135857282046, 43253.13493730045, 43343.0, 35737.46393804043, 37571.358587524475, 38428.97413166172, 38524.0, 38547.0, 28510.42979140364, 28984.0, 29065.273368165967, 29300.41399171962, 29426.411471770018], "value": 21381.865314046652, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.6911762501685, 408.2345601996257, 412.4802748536845, 421.6394982290142, 434.4786256492208, 14273.714525709493, 16241.83758162418, 19930.50173745659, 20485.07817148228, 20834.374968750923, 20308.492287692814, 20379.082941267643, 20970.056347464364, 20995.580088398223, 21184.046717897694, 5649.49154576087, 5820.35976042635, 5923.8222853314255, 5959.731812068446, 5975.282966044081, 4073.714839961204, 4273.316269396897, 5954.940450595482, 5958.40415958405, 6000.969995150018, 4302.892427689317, 4534.546545345455, 4561.1789877821975, 6599.769008084718, 6670.0328452374615, 5841.766329346821, 6644.5681030733085, 6645.202575690948, 6652.268250492466, 6666.466682665399, 5923.5261179106, 6021.668808215546, 6028.276606807167, 6030.095485677183, 6042.546808989343, 15476.22618869052, 19030.42908712727, 20051.699224511645, 24317.635235471484, 24424.26727198187, 2194.1113848891223, 2324.6745455636224, 2431.270618814349, 2571.485702859417, 3031.3331067165186, 10735.83896241557, 10862.594315864038, 11058.391788451632, 11061.889381106166, 11316.0, 1849.4544109487733, 1856.860735444847, 1863.0870873272136, 1900.5628705397698, 1932.5555122321805, 67077.0, 70892.2910770896, 72106.63946680285, 80215.0, 85874.0, 11827.763444731057, 12032.639020829389, 12097.153199276143, 12217.328046957326, 12322.383880805924, 17417.303307867798, 17431.302747890197, 17443.476695699148, 17451.47645570635, 17461.912690436468, 8837.204651581389, 9145.131212534798, 9264.39781414206, 9274.536273186313, 9283.628654853865, 9069.909300907038, 9099.226565741854, 9115.042920493386, 9235.12266334697, 9245.075492450702, 5174.24973378858, 5209.270702101709, 5248.343957005374, 5437.287254514894, 5482.780688772405, 2075.1803037799987, 2163.448320678217, 2171.3594489625284, 2172.3157205480225, 2197.846150769463, 966.6520052780993, 974.4056125763278, 981.1464026297167, 1006.1296978114025, 1053.710229686827, 19602.0, 21017.474563136046, 21251.68122478164, 21282.467938301357, 21691.0, 1018.7453136715822, 1026.573971801694, 1035.984460233097, 1068.6847380022912, 1090.7436752363187, 11116.444177791078, 11680.824787628193, 12029.09781766371, 12074.577389791315, 12103.455344509519, 421574.0, 444604.0, 471222.0, 473320.0, 475175.0, 70133.64933175403, 83287.0, 98657.0, 100606.0, 102214.48892755489, 1024.3751311699857, 1059.2055958031476, 1071.8713754349528, 1113.3041848844473, 1117.251441534174, 18721.344752933583, 18798.906005469886, 18802.341918032802, 18987.43037708871, 19257.0, 40764.796176020114, 41963.7901810489, 43107.78446107749, 45484.545154549356, 46186.538134619565, 64041.0, 64812.67593662001, 65656.0, 66406.0, 66932.665336673, 8956.328275379243, 9121.04229055966, 9125.087491250822, 9329.813403732023, 9466.384684995453, 10095.29332946716, 13669.453221871412, 13725.931370343083, 13865.237411942036, 13881.889448844271, 12448.190867593577, 12449.190802597803, 12571.308578028296, 12722.809157862825, 13179.736405272033, 12024.278543287432, 12180.512779488721, 12262.20295680778, 12395.132340736061, 12607.180533265308, 12020.879791201976, 12028.699282518008, 12251.264924104582, 12441.191322564004, 12645.620631381073, 9000.144986226298, 9273.350865439354, 9343.928482741494, 9527.190188833749, 9537.952310238405, 9425.57584908667, 12202.389880506116, 12278.07914406441, 12300.569480068154, 12776.10567260283], "value": 9637.012922416334, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1466.5673626280247, 1544.9382024719011, 1672.4062957650035, 1764.8235176482344, 1873.325602782998, 9067.274618030558, 9786.0, 9815.411075335485, 9823.214142868572, 9823.75440613985, 8248.0, 8476.449030812993, 9075.95462022689, 9342.626294948193, 9358.578863951121, 2549.502846944842, 2572.176903390916, 2579.767820896119, 2581.0579138614457, 2583.9483210335784, 2130.1160018592263, 2526.7220605733355, 2561.244432892301, 2563.333533281351, 2567.332493551671, 2282.315305408379, 2443.9144629937955, 2484.465839844433, 2505.7870081043134, 2513.070164039308, 2451.840630359021, 2467.444824914389, 2472.4807790364043, 2477.690288713911, 2549.68128983877, 2500.6374075758913, 2511.4474815540552, 2511.8744062796786, 2515.3963048868322, 2532.556802559545, 9580.808383832355, 9663.082007209305, 9695.127438530566, 9697.272704547187, 9715.562799674031, 5594.888102237933, 5749.31008279005, 5775.48020678141, 5780.826575202751, 5782.277215348082, 3612.151144481045, 3618.475321078455, 3674.889753307405, 3680.190358121209, 3680.35593771089, 1207.3842340406368, 1208.55283545088, 1218.439517821803, 1223.3516236394726, 1228.8525376954733, 13271.535496257584, 13458.05793594439, 13483.393247303895, 13505.729885402336, 13531.052826302162, 4902.950970490321, 4957.992013234646, 5035.370578677665, 5086.0845047891735, 5122.641415100908, 10534.0, 10542.525586348633, 10549.894501055045, 10556.313839600401, 10570.207234457293, 5769.0, 5769.711514424262, 5770.105633626795, 5770.422957704196, 5770.653760774367, 5318.414974352818, 5328.0675881720545, 5376.650517716336, 5394.811181608625, 5420.07858664028, 4028.1540876415693, 4064.7561146331313, 4099.077707515785, 4145.689073319513, 4156.688248381383, 1130.2427373659668, 1229.4836168809122, 1230.3110258255406, 1234.8579913309904, 1244.2472304255975, 883.2183517586853, 940.6472572785206, 992.1368409483651, 1023.8617786598862, 1045.911097556701, 6176.81469555914, 6363.300036995926, 6468.320826313264, 6471.255805582324, 6503.057056726748, 1009.4801177393648, 1033.9379637221791, 1044.6030508406757, 1060.8779990301214, 1076.7361996310954, 4275.48694156697, 4376.14665140301, 4526.932096018562, 4546.477155127137, 4549.9990002199465, 11562.190646654653, 12001.939990299992, 12005.0, 12022.338771367484, 12022.879771202175, 11265.662030139109, 11699.707507312214, 11704.648860534198, 11935.104867135, 11967.64097077089, 8702.347323950602, 8914.777130571658, 8919.152680495343, 8925.152110549487, 8929.107089291148, 6525.096332132156, 6655.800325990228, 6678.432333251728, 6695.196576410862, 6813.352731490699, 10942.069924056545, 11022.889771102185, 11030.558777648805, 11129.83305250438, 11232.83150752723, 11332.206745527734, 11402.486888090221, 11429.771404572028, 11462.541498339973, 11619.244749091282, 8396.118407567361, 8597.140285971353, 8645.697400590949, 8671.653133874574, 8700.347473939606, 9197.908020919705, 9288.535573221177, 9328.253739700944, 9336.673216437392, 9373.906260937303, 6663.333666633298, 8206.958965205136, 8304.128066552925, 8370.832583348178, 8496.91503084986, 8095.83808323842, 8149.79625509367, 8250.38122140854, 8312.625931833203, 8505.532195729294, 7747.30274275318, 7828.217178282126, 7828.765137045898, 7828.921710782819, 7830.138684744559, 5387.757550910297, 5481.862953426196, 5511.862203444785, 5525.917111243415, 5547.472990065938, 5608.07466767995, 5681.715914204356, 5746.9712651436475, 5758.021136406737, 5940.732667029907], "value": 5004.645974900819, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.9804019323864, 351.83463772027136, 352.97988812332346, 353.2017640133298, 362.1923111461441, 5599.804006859761, 5607.27105476288, 5631.971840140804, 5642.689652069134, 5659.292588426447, 5496.835094947158, 5500.532454741352, 5539.667619942807, 5547.611667183299, 5558.388577256498, 3880.417937309412, 4145.3367461206135, 4309.418228539138, 4366.126774645068, 4367.366731823884, 4356.869293921188, 4358.738475691453, 4366.8253269869165, 4372.169287835317, 4381.211381951248, 4348.239058164824, 4352.934705979413, 4374.956250437503, 4390.956090439086, 4400.691951563392, 4121.4848143982, 4153.730007549515, 4266.679999000056, 4312.439382880206, 4399.978000110011, 3796.215778584061, 3803.7147213959065, 3810.599887011879, 3815.1606646537716, 3820.465134881119, 5328.653637513549, 5346.946530534684, 5349.144136938071, 5373.865653358658, 5403.162509810985, 4820.204666230068, 4947.713594465465, 4979.651424400257, 4994.876152865612, 5023.497650234984, 5691.7723291068305, 5709.371969083398, 5786.566007549451, 5791.768329266824, 5797.913031304533, 4276.764777937212, 4285.086141423862, 4301.612854843079, 4311.116221174651, 4340.8697739067875, 5650.208970744099, 5826.388229235911, 5941.821745347646, 5959.970200149015, 5991.161237426763, 4267.871963841089, 4378.496472905656, 4392.407025051577, 4396.087701849706, 4401.053773438662, 4437.267850804646, 4451.910961780746, 4454.420925279757, 4481.327800829901, 4503.166914120898, 4898.289747986593, 5007.624428167902, 5017.949820501822, 5047.192449208109, 5062.898742025139, 5715.342735585377, 5725.312962444534, 5734.455226753452, 5740.388123403841, 5783.277090363705, 1495.4691084665005, 1524.4892960858015, 1525.916599214548, 1535.4088675859875, 1537.0, 948.3456415073578, 985.7289245457413, 1000.284796370584, 1010.6917390195914, 1024.8616436781087, 5023.246513023076, 5059.507445303644, 5064.316317297192, 5090.694558326512, 5094.286799848024, 5280.1519468185825, 5286.762095705702, 5298.443663415363, 5303.806643505258, 5322.973385133049, 4790.257510085942, 5030.84907452777, 5031.320771695848, 5038.294638750578, 5040.84877453677, 2777.986110069477, 2780.5968134620366, 2813.957790633142, 2839.0, 2842.6730925943366, 5429.484199001167, 5488.91766623501, 5511.751971161308, 5539.556835453154, 5574.247476590608, 5114.872128196825, 5348.331458567679, 5381.9461805382225, 5382.569394448435, 5405.594510442816, 5378.623496355296, 5387.4073851876265, 5422.945770542244, 5443.42844001382, 5447.836564903059, 4896.657233993728, 5236.240745092016, 5264.630916087853, 5283.463937310036, 5293.179557168645, 5111.670965549059, 5122.41205226381, 5130.178786530728, 5168.664036837594, 5181.896362072813, 5361.874006458687, 5842.364138041356, 6000.279966404059, 6026.668533230714, 6102.938970610237, 4364.498082720528, 4374.978125109354, 4389.692721509591, 4390.143921935192, 4421.182081314967, 5799.159121927381, 5996.130561068533, 6008.218931538872, 6035.486983606267, 6068.4841788448475, 5841.853953651193, 5857.384974577608, 5858.912116318344, 5863.677497737665, 5864.032434648235, 5798.82603521895, 5851.21008663842, 5855.2973643163095, 5861.794837180849, 5865.912011319749, 5531.197976293495, 5768.913466297925, 5787.0, 5795.217645617956, 5806.564507662027, 5857.355690873916, 5871.412858714094, 5880.882382352414, 5881.500072493886, 5888.793892213752, 5491.780328786804, 5740.225069615549, 5747.942520574741, 5766.971165144148, 5770.538356931519], "value": 4321.729541157739, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [802.4423025996934, 838.7903024243939, 871.8997315308742, 890.0, 931.1852129386784, 16993.2353044113, 17014.659706805858, 17052.658946821055, 17075.487735367944, 17097.40159094432, 16048.75926861098, 16255.918720406411, 16274.186290685477, 16293.51119466415, 16305.021698698087, 730.755197009, 745.526590614958, 873.6418068591879, 881.171698603314, 894.2711689972684, 886.1492966751925, 888.8933328000649, 889.1508609278156, 889.6352495476856, 890.0254221627308, 931.1805611062256, 931.9720408387759, 932.7201839448138, 932.7574830544039, 933.7058826469674, 764.8470305938806, 789.7591234673422, 792.2196636313226, 794.0986979777936, 920.3419555018161, 663.515633587479, 683.8871586188272, 685.0204207982583, 687.4603436302529, 688.4595592459945, 15883.047017178891, 15982.0, 16019.11894845783, 16044.0, 16053.598660033476, 15177.0, 15296.235188240544, 15404.614884627861, 15443.536693899201, 15518.922405388013, 13166.73666526664, 13497.662558436019, 13514.864851351458, 13893.58319250414, 13984.650383740387, 4491.595756381941, 4622.930656040128, 4665.183592871234, 4758.191107511697, 4763.0, 76734.0, 76827.61586192089, 81768.59115704383, 86896.13103868943, 89036.55481722615, 10856.945715271373, 11167.106631469464, 11217.326960382403, 11246.381449020382, 11405.828912566318, 23150.0, 23163.305100846665, 23182.884085579462, 23234.0, 23241.76758232396, 8636.870446943301, 8684.305255579538, 8879.0, 9027.277817774562, 9176.6788162414, 4699.8320426878545, 4873.098476781806, 4999.925001124986, 5096.464871188546, 5158.0199762045095, 3465.2203254268093, 3560.732945029133, 3568.2506673598314, 3586.7668601540818, 3591.5510561179854, 646.2342124582385, 650.4048795352294, 661.3056290894541, 662.413763819014, 662.6454846657016, 685.348918527401, 691.837418206721, 702.1714377035148, 702.7751119641767, 727.4798519058899, 9641.325107242566, 9683.370580912218, 9730.72154723531, 9801.705948821547, 9855.45794981283, 627.9560430769893, 686.0361192524512, 692.1659400422479, 696.084648686975, 696.3732640623379, 4612.76936153191, 4760.262159365235, 4781.330613714083, 4812.614990800727, 4827.831025914076, 76154.61922690351, 109996.35005474924, 129898.35050824685, 133002.0, 138607.0, 90385.0, 90716.5464172675, 91757.0, 113780.0, 116921.41539292419, 72511.63744181245, 90902.0, 91019.54490227507, 91240.54379728192, 94200.52899735594, 8903.643854246016, 9124.908750912671, 9316.813663726824, 9346.299027573095, 9747.561359738971, 56151.71924140353, 57484.42515574791, 58392.0, 58817.0, 59421.086287518694, 82392.0, 83938.58030709808, 84936.0, 85388.57305713432, 87034.0, 12348.38258087074, 12659.746805064031, 12706.555270565486, 12937.741245174855, 12976.675583110498, 15028.398864045315, 15054.774178387552, 15151.545453636409, 15299.311530980909, 15662.216889155725, 13592.320383980563, 13952.372143253375, 14283.428662853785, 14304.856951430353, 14746.557603271918, 13760.105593136414, 14027.859721403063, 14029.929850350683, 14106.506272280421, 14304.213268270345, 13859.584212473643, 14285.142891426547, 14403.711925761636, 14417.062890912057, 14448.205348705922, 11835.28988260707, 11937.582184623496, 12359.75280494403, 12606.306653134166, 12706.110572259853, 11295.0, 11577.884221157681, 11919.225250358699, 12231.12568546373, 12261.26432414058], "value": 7955.464093216213, "unit": "score"}], "name": "dromaeo_css", "value": 7641.601328668345, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 110.0, 110.0, 111.0, 111.0, 109.0, 110.0, 111.0, 110.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 123.0, 158.0, 123.0, 123.0, 116.0, 120.0, 150.0, 120.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 159.0, 161.0, 161.0, 161.0, 160.0, 161.0, 161.0, 162.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 75.0, 105.0, 76.0, 76.0, 75.0, 75.0, 98.0, 75.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 109.0, 82.0, 81.0, 82.0, 99.0, 82.0, 82.0, 103.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 119.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 147.0, 148.0, 147.0, 146.0, 146.0, 146.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 96.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 47.0, 47.0, 47.0, 51.0, 48.0, 49.0, 47.0, 47.0, 48.0], "value": 48.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 79.0, 74.0, 74.0, 73.0, 73.0, 73.0, 78.0, 77.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 133.0, 118.0, 127.0, 115.0, 127.0, 118.0, 118.0, 164.0, 119.0], "value": 126.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 154.0, 153.0, 151.0, 151.0, 157.0, 150.0, 149.0, 155.0, 157.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 61.0, 61.0, 61.0, 62.0, 62.0, 63.0, 64.0, 61.0], "value": 62.0, "unit": "score"}], "name": "kraken", "value": 1452.5, "alertThreshold": 2.0}]} 01:37:49 INFO - Return code: 0 01:37:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:37:49 INFO - # TBPL SUCCESS # 01:37:49 INFO - Running post-action listener: _resource_record_post_action 01:37:49 INFO - [mozharness: 2016-09-20 08:37:49.590092Z] Finished run-tests step (success) 01:37:49 INFO - Running post-run listener: _resource_record_post_run 01:37:49 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 262144; Write bytes: 355352576; Read time: 20; Write time: 306764 01:37:49 INFO - TinderboxPrint: CPU usage
13.0% 01:37:49 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 20 01:37:49 INFO - TinderboxPrint: I/O write bytes / time
355,352,576 / 306,764 01:37:49 INFO - TinderboxPrint: CPU idle
8,259.9 (87.0%) 01:37:49 INFO - TinderboxPrint: CPU user
1,191.4 (12.5%) 01:37:49 INFO - TinderboxPrint: Swap in / out
0 / 0 01:37:49 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107266048; Read time: 0; Write time: 87144 01:37:49 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 248086528; Read time: 20; Write time: 219620 01:37:50 INFO - Running post-run listener: _upload_blobber_files 01:37:50 INFO - Blob upload gear active. 01:37:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:37:50 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 01:37:50 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:37:50 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:37:50 INFO - (blobuploader) - INFO - Open directory for files ... 01:37:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:37:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:37:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:37:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:37:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:37:51 INFO - (blobuploader) - INFO - Done attempting. 01:37:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:37:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:37:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:37:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:37:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:37:52 INFO - (blobuploader) - INFO - Done attempting. 01:37:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:37:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:37:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:37:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:37:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:37:56 INFO - (blobuploader) - INFO - Done attempting. 01:37:56 INFO - (blobuploader) - INFO - Iteration through files over. 01:37:56 INFO - Return code: 0 01:37:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:37:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:37:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6d4baf320a6906aa1178f5e65e099c8824dd04fc65ae4148ada294c83874179dffc782fa1e2a90476754d8f8d1dc8441ed81cf62bd5919b38ef142e4920516f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/355c1eb36510b66d1ebbe61c409d44bb0ec421795c85bc3dbb71254c086f409c39477749aecce69d225530e5beb518ccd3f5ab9fc6fc40a22e443142812925d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2cdda21ce6e14b2ac095b0dcecbf2a1696e58929feca92fdd8580504852a7e2c2304bfcf75112fe69ab659e5bb1628e7bf745f1f796ee413c13bacbd6db7889"} 01:37:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:37:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:37:56 INFO - Contents: 01:37:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6d4baf320a6906aa1178f5e65e099c8824dd04fc65ae4148ada294c83874179dffc782fa1e2a90476754d8f8d1dc8441ed81cf62bd5919b38ef142e4920516f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/355c1eb36510b66d1ebbe61c409d44bb0ec421795c85bc3dbb71254c086f409c39477749aecce69d225530e5beb518ccd3f5ab9fc6fc40a22e443142812925d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2cdda21ce6e14b2ac095b0dcecbf2a1696e58929feca92fdd8580504852a7e2c2304bfcf75112fe69ab659e5bb1628e7bf745f1f796ee413c13bacbd6db7889"} 01:37:56 INFO - Running post-run listener: copy_logs_to_upload_dir 01:37:56 INFO - Copying logs to upload dir... 01:37:56 INFO - mkdir: /builds/slave/test/build/upload/logs 01:37:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.709623 ========= master_lag: 0.61 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-09-20 01:37:57.434122) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-09-20 01:37:57.439104) ========= 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=17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6d4baf320a6906aa1178f5e65e099c8824dd04fc65ae4148ada294c83874179dffc782fa1e2a90476754d8f8d1dc8441ed81cf62bd5919b38ef142e4920516f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/355c1eb36510b66d1ebbe61c409d44bb0ec421795c85bc3dbb71254c086f409c39477749aecce69d225530e5beb518ccd3f5ab9fc6fc40a22e443142812925d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2cdda21ce6e14b2ac095b0dcecbf2a1696e58929feca92fdd8580504852a7e2c2304bfcf75112fe69ab659e5bb1628e7bf745f1f796ee413c13bacbd6db7889"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011282 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474323497/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6d4baf320a6906aa1178f5e65e099c8824dd04fc65ae4148ada294c83874179dffc782fa1e2a90476754d8f8d1dc8441ed81cf62bd5919b38ef142e4920516f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/355c1eb36510b66d1ebbe61c409d44bb0ec421795c85bc3dbb71254c086f409c39477749aecce69d225530e5beb518ccd3f5ab9fc6fc40a22e443142812925d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e2cdda21ce6e14b2ac095b0dcecbf2a1696e58929feca92fdd8580504852a7e2c2304bfcf75112fe69ab659e5bb1628e7bf745f1f796ee413c13bacbd6db7889"}' ========= master_lag: 2.31 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-09-20 01:37:59.764132) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:37:59.764426) ========= 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=17051fcd5208b27b50bedfeb000001dd-1474358944.711264-2080555693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004552 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:37:59.799355) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 01:37:59.799673) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 01:37:59.800760) ========= ========= Total master_lag: 3.31 =========