builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1474407813.88 results: success (0) buildid: 20160920141933 builduid: f6187afb3b6044a6a125cc11fb55f084 revision: e7108b1c6731f1456ee875ed32e469c5eb7df1c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 14:43:33.877402) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 14:43:33.878215) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 14:43:33.878725) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 14:43:33.895059) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:43:33.895379) ========= 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=8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 14:43:34-- 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% 70.8M=0s 2016-09-20 14:43:34 (70.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082956 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:43:33.994444) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:43:33.995149) ========= 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=8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018655 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:43:34.047428) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 14:43:34.047832) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e7108b1c6731f1456ee875ed32e469c5eb7df1c1 --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 e7108b1c6731f1456ee875ed32e469c5eb7df1c1 --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=8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 14:43:34,294 truncating revision to first 12 chars 2016-09-20 14:43:34,294 Setting DEBUG logging. 2016-09-20 14:43:34,294 attempt 1/10 2016-09-20 14:43:34,294 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e7108b1c6731?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 14:43:35,271 unpacking tar archive at: mozilla-aurora-e7108b1c6731/testing/mozharness/ program finished with exit code 0 elapsedTime=1.322293 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 14:43:35.384000) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 14:43:35.384365) ========= script_repo_revision: e7108b1c6731f1456ee875ed32e469c5eb7df1c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 14:43:35.384811) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 14:43:35.385133) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 14:43:35.394656) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-09-20 14:43:35.394972) ========= /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=8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:43:35 INFO - MultiFileLogger online at 20160920 14:43:35 in /builds/slave/test 14:43:35 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 14:43:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:43:35 INFO - {'append_to_log': False, 14:43:35 INFO - 'base_work_dir': '/builds/slave/test', 14:43:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:43:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:43:35 INFO - 'branch': 'Mozilla-Aurora', 14:43:35 INFO - 'buildbot_json_path': 'buildprops.json', 14:43:35 INFO - 'config_files': ('talos/linux_config.py',), 14:43:35 INFO - 'default_actions': ('clobber', 14:43:35 INFO - 'read-buildbot-config', 14:43:35 INFO - 'download-and-extract', 14:43:35 INFO - 'populate-webroot', 14:43:35 INFO - 'create-virtualenv', 14:43:35 INFO - 'install', 14:43:35 INFO - 'run-tests'), 14:43:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:43:35 INFO - 'download_minidump_stackwalk': True, 14:43:35 INFO - 'download_symbols': 'ondemand', 14:43:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:43:35 INFO - 'tooltool.py': '/tools/tooltool.py', 14:43:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:43:35 INFO - '/tools/misc-python/virtualenv.py')}, 14:43:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:43:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:43:35 INFO - 'installer_path': 'installer.exe', 14:43:35 INFO - 'log_level': 'info', 14:43:35 INFO - 'log_name': 'talos', 14:43:35 INFO - 'log_to_console': True, 14:43:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:43:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:43:35 INFO - 'opt_config_files': (), 14:43:35 INFO - 'pip_index': False, 14:43:35 INFO - 'sps_profile': False, 14:43:35 INFO - 'sps_profile_interval': 0, 14:43:35 INFO - 'suite': 'dromaeojs', 14:43:35 INFO - 'system_bits': '32', 14:43:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:43:35 INFO - 'title': 'talos-linux64-ix-038', 14:43:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:43:35 INFO - 'use_talos_json': True, 14:43:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:43:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:43:35 INFO - 'work_dir': 'build'} 14:43:35 INFO - [mozharness: 2016-09-20 21:43:35.732994Z] Running clobber step. 14:43:35 INFO - Running pre-action listener: _resource_record_pre_action 14:43:35 INFO - Running main action method: clobber 14:43:35 INFO - rmtree: /builds/slave/test/build 14:43:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:43:36 INFO - Running post-action listener: _resource_record_post_action 14:43:36 INFO - [mozharness: 2016-09-20 21:43:36.188070Z] Finished clobber step (success) 14:43:36 INFO - [mozharness: 2016-09-20 21:43:36.188175Z] Running read-buildbot-config step. 14:43:36 INFO - Running pre-action listener: _resource_record_pre_action 14:43:36 INFO - Running main action method: read_buildbot_config 14:43:36 INFO - Using buildbot properties: 14:43:36 INFO - { 14:43:36 INFO - "project": "", 14:43:36 INFO - "product": "firefox", 14:43:36 INFO - "script_repo_revision": "production", 14:43:36 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 14:43:36 INFO - "repository": "", 14:43:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 14:43:36 INFO - "buildid": "20160920141933", 14:43:36 INFO - "stage_platform": "linux64-pgo", 14:43:36 INFO - "basedir": "/builds/slave/test", 14:43:36 INFO - "buildnumber": 479, 14:43:36 INFO - "platform": "ubuntu64_hw", 14:43:36 INFO - "mozci_request": { 14:43:36 INFO - "priority": -1, 14:43:36 INFO - "type": "trigger_all_talos_jobs", 14:43:36 INFO - "times": 6 14:43:36 INFO - }, 14:43:36 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:43:36 INFO - "slavebuilddir": "test", 14:43:36 INFO - "branch": "mozilla-aurora", 14:43:36 INFO - "revision": "e7108b1c6731f1456ee875ed32e469c5eb7df1c1", 14:43:36 INFO - "slavename": "talos-linux64-ix-038", 14:43:36 INFO - "builduid": "f6187afb3b6044a6a125cc11fb55f084", 14:43:36 INFO - "repo_path": "releases/mozilla-aurora" 14:43:36 INFO - } 14:43:36 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 14:43:36 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.test_packages.json. 14:43:36 INFO - Running post-action listener: _resource_record_post_action 14:43:36 INFO - [mozharness: 2016-09-20 21:43:36.190916Z] Finished read-buildbot-config step (success) 14:43:36 INFO - [mozharness: 2016-09-20 21:43:36.191005Z] Running download-and-extract step. 14:43:36 INFO - Running pre-action listener: _resource_record_pre_action 14:43:36 INFO - Running main action method: download_and_extract 14:43:36 INFO - mkdir: /builds/slave/test/build/tests 14:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:36 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 14:43:36 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/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 14:43:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/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 14:43:36 INFO - Downloaded 1361 bytes. 14:43:36 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 14:43:36 INFO - Using the following test package requirements: 14:43:36 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 14:43:36 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:43:36 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 14:43:36 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:43:36 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 14:43:36 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:43:36 INFO - u'jsshell-linux-x86_64.zip'], 14:43:36 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:43:36 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 14:43:36 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 14:43:36 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:43:36 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 14:43:36 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:43:36 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 14:43:36 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:43:36 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 14:43:36 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:43:36 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 14:43:36 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:43:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 14:43:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:43:36 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 14:43:36 INFO - Expected file size: 21894243 14:43:40 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 14:43:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 14:43:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:43:40 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 14:43:40 INFO - Expected file size: 21894243 14:43:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 14:43:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:43:45 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 14:43:45 INFO - Expected file size: 14414059 14:43: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')]} 14:43:45 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 14:43:45 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/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 14:43:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/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 14:43:46 INFO - Downloaded 63971086 bytes. 14:43:46 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 14:43:46 INFO - mkdir: /builds/slave/test/properties 14:43:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:43:46 INFO - Writing to file /builds/slave/test/properties/build_url 14:43:46 INFO - Contents: 14:43:46 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 14:43:46 INFO - Running post-action listener: _resource_record_post_action 14:43:46 INFO - Running post-action listener: set_extra_try_arguments 14:43:46 INFO - [mozharness: 2016-09-20 21:43:46.698544Z] Finished download-and-extract step (success) 14:43:46 INFO - [mozharness: 2016-09-20 21:43:46.698771Z] Running populate-webroot step. 14:43:46 INFO - Running pre-action listener: _resource_record_pre_action 14:43:46 INFO - Running main action method: populate_webroot 14:43:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:43:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:43:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:43:46 INFO - '--tppagecycles', 14:43:46 INFO - '7'], 14:43:46 INFO - 'tests': ['tsvgm']}}, 14:43:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:43:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:43:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:43:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:43:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:43:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:43:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:46 INFO - 'talos_options': ['--disable-e10s'], 14:43:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:43:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:43:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:46 INFO - 'talos_options': ['--disable-e10s'], 14:43:46 INFO - 'tests': ['damp', 'tps']}, 14:43:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:46 INFO - 'tests': ['damp', 'tps']}, 14:43:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:43:46 INFO - 'tests': ['dromaeo_dom']}, 14:43:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:43:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:43:46 INFO - 'tests': ['basic_compositor_video']}, 14:43:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:43:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:43:46 INFO - 'tests': ['a11yr', 14:43:46 INFO - 'ts_paint', 14:43:46 INFO - 'tpaint', 14:43:46 INFO - 'sessionrestore', 14:43:46 INFO - 'sessionrestore_no_auto_restore', 14:43:46 INFO - 'tabpaint']}, 14:43:46 INFO - 'other-e10s': {'tests': ['a11yr', 14:43:46 INFO - 'ts_paint', 14:43:46 INFO - 'tpaint', 14:43:46 INFO - 'sessionrestore', 14:43:46 INFO - 'sessionrestore_no_auto_restore', 14:43:46 INFO - 'tabpaint']}, 14:43:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:43:46 INFO - 'ts_paint', 14:43:46 INFO - 'tpaint', 14:43:46 INFO - 'sessionrestore', 14:43:46 INFO - 'sessionrestore_no_auto_restore', 14:43:46 INFO - 'tabpaint']}, 14:43:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:43:46 INFO - 'ts_paint', 14:43:46 INFO - 'tpaint', 14:43:46 INFO - 'sessionrestore', 14:43:46 INFO - 'sessionrestore_no_auto_restore', 14:43:46 INFO - 'tabpaint']}, 14:43:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:43:46 INFO - 'tests': ['a11yr', 14:43:46 INFO - 'ts_paint', 14:43:46 INFO - 'tpaint', 14:43:46 INFO - 'sessionrestore', 14:43:46 INFO - 'sessionrestore_no_auto_restore', 14:43:46 INFO - 'tabpaint']}, 14:43:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:43:46 INFO - 'tests': ['a11yr', 14:43:46 INFO - 'ts_paint', 14:43:46 INFO - 'tpaint', 14:43:46 INFO - 'sessionrestore', 14:43:46 INFO - 'sessionrestore_no_auto_restore', 14:43:46 INFO - 'tabpaint']}, 14:43:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:43:46 INFO - 'tests': ['tsvgx', 14:43:46 INFO - 'tsvgr_opacity', 14:43:46 INFO - 'tart', 14:43:46 INFO - 'tscrollx', 14:43:46 INFO - 'cart']}, 14:43:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:43:46 INFO - 'tsvgr_opacity', 14:43:46 INFO - 'tart', 14:43:46 INFO - 'tscrollx', 14:43:46 INFO - 'cart']}, 14:43:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:46 INFO - 'talos_options': ['--disable-e10s'], 14:43:46 INFO - 'tests': ['tp5o']}, 14:43:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:46 INFO - 'tests': ['tp5o']}, 14:43:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:46 INFO - 'talos_options': ['--disable-e10s', 14:43:46 INFO - '--xperf_path', 14:43:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:43:46 INFO - 'tests': ['tp5n']}, 14:43:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:46 INFO - 'talos_options': ['--xperf_path', 14:43:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:43:46 INFO - 'tests': ['tp5n']}}, 14:43:46 INFO - 'talos.zip': {'path': '', 14:43:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:43:46 INFO - Running post-action listener: _resource_record_post_action 14:43:46 INFO - [mozharness: 2016-09-20 21:43:46.718014Z] Finished populate-webroot step (success) 14:43:46 INFO - [mozharness: 2016-09-20 21:43:46.718102Z] Running create-virtualenv step. 14:43:46 INFO - Running pre-action listener: _resource_record_pre_action 14:43:46 INFO - Running main action method: create_virtualenv 14:43:46 INFO - Creating virtualenv /builds/slave/test/build/venv 14:43:46 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 14:43:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:43:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:43:46 INFO - Using real prefix '/usr' 14:43:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:43:47 INFO - Installing distribute.............................................................................................................................................................................................done. 14:43:49 INFO - Installing pip.................done. 14:43:49 INFO - Return code: 0 14:43:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f46ab56ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2135ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132f60>, '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 0x2133d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21341d0>, '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': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', '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 14:43:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:43:49 INFO - Using env: {'DISPLAY': ':0', 14:43:49 INFO - 'HOME': '/home/cltbld', 14:43:49 INFO - 'LANG': 'en_US.UTF-8', 14:43:49 INFO - 'LANGUAGE': 'en_US:en', 14:43:49 INFO - 'LOGNAME': 'cltbld', 14:43:49 INFO - 'MAIL': '/var/mail/cltbld', 14:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:49 INFO - 'MOZ_NO_REMOTE': '1', 14:43:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:49 INFO - 'NO_EM_RESTART': '1', 14:43:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:49 INFO - 'PWD': '/builds/slave/test', 14:43:49 INFO - 'SHELL': '/bin/bash', 14:43:49 INFO - 'SHLVL': '1', 14:43:49 INFO - 'TERM': 'linux', 14:43:49 INFO - 'TMOUT': '86400', 14:43:49 INFO - 'USER': 'cltbld', 14:43:49 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', 14:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:49 INFO - '_': '/tools/buildbot/bin/python'} 14:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:49 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 14:43:49 INFO - Cleaning up... 14:43:49 INFO - Return code: 0 14:43:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f46ab56ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2135ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132f60>, '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 0x2133d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21341d0>, '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': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', '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 14:43:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:43:49 INFO - Using env: {'DISPLAY': ':0', 14:43:49 INFO - 'HOME': '/home/cltbld', 14:43:49 INFO - 'LANG': 'en_US.UTF-8', 14:43:49 INFO - 'LANGUAGE': 'en_US:en', 14:43:49 INFO - 'LOGNAME': 'cltbld', 14:43:49 INFO - 'MAIL': '/var/mail/cltbld', 14:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:49 INFO - 'MOZ_NO_REMOTE': '1', 14:43:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:49 INFO - 'NO_EM_RESTART': '1', 14:43:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:49 INFO - 'PWD': '/builds/slave/test', 14:43:49 INFO - 'SHELL': '/bin/bash', 14:43:49 INFO - 'SHLVL': '1', 14:43:49 INFO - 'TERM': 'linux', 14:43:49 INFO - 'TMOUT': '86400', 14:43:49 INFO - 'USER': 'cltbld', 14:43:49 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', 14:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:49 INFO - '_': '/tools/buildbot/bin/python'} 14:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:49 INFO - Downloading/unpacking psutil>=3.1.1 14:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:43:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:43:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:43:51 INFO - Installing collected packages: psutil 14:43:51 INFO - Running setup.py install for psutil 14:43:51 INFO - building 'psutil._psutil_linux' extension 14:43:51 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 14:43:51 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 14:43:51 INFO - building 'psutil._psutil_posix' extension 14:43:51 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 14:43:52 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 14:43:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:43:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:43:52 INFO - Successfully installed psutil 14:43:52 INFO - Cleaning up... 14:43:52 INFO - Return code: 0 14:43:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f46ab56ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2135ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132f60>, '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 0x2133d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21341d0>, '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': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', '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 14:43:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:43:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:43:52 INFO - Using env: {'DISPLAY': ':0', 14:43:52 INFO - 'HOME': '/home/cltbld', 14:43:52 INFO - 'LANG': 'en_US.UTF-8', 14:43:52 INFO - 'LANGUAGE': 'en_US:en', 14:43:52 INFO - 'LOGNAME': 'cltbld', 14:43:52 INFO - 'MAIL': '/var/mail/cltbld', 14:43:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:52 INFO - 'MOZ_NO_REMOTE': '1', 14:43:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:52 INFO - 'NO_EM_RESTART': '1', 14:43:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:52 INFO - 'PWD': '/builds/slave/test', 14:43:52 INFO - 'SHELL': '/bin/bash', 14:43:52 INFO - 'SHLVL': '1', 14:43:52 INFO - 'TERM': 'linux', 14:43:52 INFO - 'TMOUT': '86400', 14:43:52 INFO - 'USER': 'cltbld', 14:43:52 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', 14:43:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:52 INFO - '_': '/tools/buildbot/bin/python'} 14:43:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:43:52 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 14:43:52 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 14:43:52 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 14:43:52 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 14:43:52 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 14:43:52 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 14:43:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:43:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:43:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:43:54 INFO - Installing collected packages: mozsystemmonitor 14:43:54 INFO - Running setup.py install for mozsystemmonitor 14:43:54 INFO - Successfully installed mozsystemmonitor 14:43:54 INFO - Cleaning up... 14:43:54 INFO - Return code: 0 14:43:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:43: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')]} 14:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43: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')]} 14:43:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f46ab56ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2135ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132f60>, '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 0x2133d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21341d0>, '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': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', '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 14:43:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:43:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:43:54 INFO - Using env: {'DISPLAY': ':0', 14:43:54 INFO - 'HOME': '/home/cltbld', 14:43:54 INFO - 'LANG': 'en_US.UTF-8', 14:43:54 INFO - 'LANGUAGE': 'en_US:en', 14:43:54 INFO - 'LOGNAME': 'cltbld', 14:43:54 INFO - 'MAIL': '/var/mail/cltbld', 14:43:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:54 INFO - 'MOZ_NO_REMOTE': '1', 14:43:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:54 INFO - 'NO_EM_RESTART': '1', 14:43:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:54 INFO - 'PWD': '/builds/slave/test', 14:43:54 INFO - 'SHELL': '/bin/bash', 14:43:54 INFO - 'SHLVL': '1', 14:43:54 INFO - 'TERM': 'linux', 14:43:54 INFO - 'TMOUT': '86400', 14:43:54 INFO - 'USER': 'cltbld', 14:43:54 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', 14:43:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:54 INFO - '_': '/tools/buildbot/bin/python'} 14:43:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:54 INFO - Downloading/unpacking jsonschema==2.5.1 14:43:54 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 14:43:54 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 14:43:54 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 14:43:54 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 14:43:54 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 14:43:54 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 14:43:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:43:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:43: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 14:43: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 14:43: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 14:43: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 14:43: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 14:43: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 14:43:57 INFO - Downloading functools32-3.2.3-2.tar.gz 14:43:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:43:57 INFO - warning: no files found matching '*.txt' 14:43:57 INFO - no previously-included directories found matching 'build' 14:43:57 INFO - no previously-included directories found matching 'dist' 14:43:57 INFO - no previously-included directories found matching '.git*' 14:43:57 INFO - Installing collected packages: jsonschema, functools32 14:43:57 INFO - Running setup.py install for functools32 14:43:57 INFO - warning: no files found matching '*.txt' 14:43:57 INFO - no previously-included directories found matching 'build' 14:43:57 INFO - no previously-included directories found matching 'dist' 14:43:57 INFO - no previously-included directories found matching '.git*' 14:43:57 INFO - Successfully installed jsonschema functools32 14:43:57 INFO - Cleaning up... 14:43:57 INFO - Return code: 0 14:43:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:43:57 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')]} 14:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:57 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')]} 14:43:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:57 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 0x7f46ab56ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2135ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132f60>, '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 0x2133d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21341d0>, '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': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', '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 14:43:57 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 14:43:57 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 14:43:57 INFO - Using env: {'DISPLAY': ':0', 14:43:57 INFO - 'HOME': '/home/cltbld', 14:43:57 INFO - 'LANG': 'en_US.UTF-8', 14:43:57 INFO - 'LANGUAGE': 'en_US:en', 14:43:57 INFO - 'LOGNAME': 'cltbld', 14:43:57 INFO - 'MAIL': '/var/mail/cltbld', 14:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:57 INFO - 'MOZ_NO_REMOTE': '1', 14:43:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:57 INFO - 'NO_EM_RESTART': '1', 14:43:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:57 INFO - 'PWD': '/builds/slave/test', 14:43:57 INFO - 'SHELL': '/bin/bash', 14:43:57 INFO - 'SHLVL': '1', 14:43:57 INFO - 'TERM': 'linux', 14:43:57 INFO - 'TMOUT': '86400', 14:43:57 INFO - 'USER': 'cltbld', 14:43:57 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', 14:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:57 INFO - '_': '/tools/buildbot/bin/python'} 14:43:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:43:57 INFO - Cleaning up... 14:43:57 INFO - Return code: 0 14:43:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:43:57 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')]} 14:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:57 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')]} 14:43:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:57 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 0x7f46ab56ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2135ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132f60>, '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 0x2133d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21341d0>, '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': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', '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 14:43:57 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 14:43:57 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 14:43:57 INFO - Using env: {'DISPLAY': ':0', 14:43:57 INFO - 'HOME': '/home/cltbld', 14:43:57 INFO - 'LANG': 'en_US.UTF-8', 14:43:57 INFO - 'LANGUAGE': 'en_US:en', 14:43:57 INFO - 'LOGNAME': 'cltbld', 14:43:57 INFO - 'MAIL': '/var/mail/cltbld', 14:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:57 INFO - 'MOZ_NO_REMOTE': '1', 14:43:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:57 INFO - 'NO_EM_RESTART': '1', 14:43:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:57 INFO - 'PWD': '/builds/slave/test', 14:43:57 INFO - 'SHELL': '/bin/bash', 14:43:57 INFO - 'SHLVL': '1', 14:43:57 INFO - 'TERM': 'linux', 14:43:57 INFO - 'TMOUT': '86400', 14:43:57 INFO - 'USER': 'cltbld', 14:43:57 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', 14:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:57 INFO - '_': '/tools/buildbot/bin/python'} 14:43:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:57 INFO - Downloading/unpacking blobuploader==1.2.4 14:43:57 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 14:43:57 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 14:43:57 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 14:43:57 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 14:43:57 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 14:43:57 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 14:43:59 INFO - Downloading blobuploader-1.2.4.tar.gz 14:43:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:43:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:43:59 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 14:43:59 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 14:43:59 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 14:43:59 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 14:43:59 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 14:43:59 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 14:43:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:44:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:00 INFO - Downloading docopt-0.6.1.tar.gz 14:44:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:44:00 INFO - Installing collected packages: blobuploader, requests, docopt 14:44:00 INFO - Running setup.py install for blobuploader 14:44:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:44:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:44:00 INFO - Running setup.py install for requests 14:44:00 INFO - Running setup.py install for docopt 14:44:00 INFO - Successfully installed blobuploader requests docopt 14:44:00 INFO - Cleaning up... 14:44:01 INFO - Return code: 0 14:44:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:44:01 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')]} 14:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:01 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')]} 14:44:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:01 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 0x7f46ab56ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2135ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132f60>, '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 0x2133d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21341d0>, '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': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', '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 14:44:01 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 14:44:01 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 14:44:01 INFO - Using env: {'DISPLAY': ':0', 14:44:01 INFO - 'HOME': '/home/cltbld', 14:44:01 INFO - 'LANG': 'en_US.UTF-8', 14:44:01 INFO - 'LANGUAGE': 'en_US:en', 14:44:01 INFO - 'LOGNAME': 'cltbld', 14:44:01 INFO - 'MAIL': '/var/mail/cltbld', 14:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:01 INFO - 'MOZ_NO_REMOTE': '1', 14:44:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:44:01 INFO - 'NO_EM_RESTART': '1', 14:44:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:01 INFO - 'PWD': '/builds/slave/test', 14:44:01 INFO - 'SHELL': '/bin/bash', 14:44:01 INFO - 'SHLVL': '1', 14:44:01 INFO - 'TERM': 'linux', 14:44:01 INFO - 'TMOUT': '86400', 14:44:01 INFO - 'USER': 'cltbld', 14:44:01 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', 14:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:01 INFO - '_': '/tools/buildbot/bin/python'} 14:44:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:44:01 INFO - Running setup.py (path:/tmp/pip-39QQYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:44:01 INFO - Running setup.py (path:/tmp/pip-6X8AQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:44:01 INFO - Running setup.py (path:/tmp/pip-w369wD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:44:01 INFO - Running setup.py (path:/tmp/pip-C6658J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:44:01 INFO - Running setup.py (path:/tmp/pip-9kQSeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:44:01 INFO - Running setup.py (path:/tmp/pip-q5cyiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:44:01 INFO - Running setup.py (path:/tmp/pip-nGWSRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:44:01 INFO - Running setup.py (path:/tmp/pip-1YUAJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:44:01 INFO - Running setup.py (path:/tmp/pip-I2Kfpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:44:01 INFO - Running setup.py (path:/tmp/pip-k4x1gR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:44:01 INFO - Running setup.py (path:/tmp/pip-_6SVZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:44:02 INFO - Running setup.py (path:/tmp/pip-F3SdmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:44:02 INFO - Running setup.py (path:/tmp/pip-g_6QW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:44:02 INFO - Running setup.py (path:/tmp/pip-BKLkr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:44:02 INFO - Running setup.py (path:/tmp/pip-0iL7n2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:44:02 INFO - Running setup.py (path:/tmp/pip-KhGkRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:44:02 INFO - Running setup.py (path:/tmp/pip-9FWBhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:44:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:44:02 INFO - Running setup.py install for manifestparser 14:44:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:44:02 INFO - Running setup.py install for mozcrash 14:44:02 INFO - Running setup.py install for mozdebug 14:44:02 INFO - Running setup.py install for mozdevice 14:44:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:44:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:44:02 INFO - Running setup.py install for mozfile 14:44:02 INFO - Running setup.py install for mozhttpd 14:44:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:44:03 INFO - Running setup.py install for mozinfo 14:44:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:44:03 INFO - Running setup.py install for mozInstall 14:44:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:44:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:44:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:44:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:44:03 INFO - Running setup.py install for mozleak 14:44:03 INFO - Running setup.py install for mozlog 14:44:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:44:03 INFO - Running setup.py install for moznetwork 14:44:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:44:03 INFO - Running setup.py install for mozprocess 14:44:03 INFO - Running setup.py install for mozprofile 14:44:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:44:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:44:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:44:03 INFO - Running setup.py install for mozrunner 14:44:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:44:03 INFO - Running setup.py install for mozscreenshot 14:44:04 INFO - Running setup.py install for moztest 14:44:04 INFO - Running setup.py install for mozversion 14:44:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:44:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:44:04 INFO - Cleaning up... 14:44:04 INFO - Return code: 0 14:44:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:44:04 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')]} 14:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:04 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')]} 14:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:04 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 0x7f46ab56ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2135ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132f60>, '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 0x2133d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21341d0>, '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': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', '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 14:44:04 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 14:44:04 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 14:44:04 INFO - Using env: {'DISPLAY': ':0', 14:44:04 INFO - 'HOME': '/home/cltbld', 14:44:04 INFO - 'LANG': 'en_US.UTF-8', 14:44:04 INFO - 'LANGUAGE': 'en_US:en', 14:44:04 INFO - 'LOGNAME': 'cltbld', 14:44:04 INFO - 'MAIL': '/var/mail/cltbld', 14:44:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:04 INFO - 'MOZ_NO_REMOTE': '1', 14:44:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:44:04 INFO - 'NO_EM_RESTART': '1', 14:44:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:44:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:04 INFO - 'PWD': '/builds/slave/test', 14:44:04 INFO - 'SHELL': '/bin/bash', 14:44:04 INFO - 'SHLVL': '1', 14:44:04 INFO - 'TERM': 'linux', 14:44:04 INFO - 'TMOUT': '86400', 14:44:04 INFO - 'USER': 'cltbld', 14:44:04 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', 14:44:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:04 INFO - '_': '/tools/buildbot/bin/python'} 14:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:44:04 INFO - Running setup.py (path:/tmp/pip-cNpupK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:44:04 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)) 14:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:44:04 INFO - Running setup.py (path:/tmp/pip-PkCKCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:44:04 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)) 14:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:44:04 INFO - Running setup.py (path:/tmp/pip-Kev2n9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:44:04 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)) 14:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:44:04 INFO - Running setup.py (path:/tmp/pip-ORDm1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:44:04 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)) 14:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:44:04 INFO - Running setup.py (path:/tmp/pip-QBeBa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:44:04 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)) 14:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:44:04 INFO - Running setup.py (path:/tmp/pip-XiSfoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:44:04 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)) 14:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:44:04 INFO - Running setup.py (path:/tmp/pip-Jp_tHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:44:04 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)) 14:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:44:05 INFO - Running setup.py (path:/tmp/pip-tBGwSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:44:05 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)) 14:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:44:05 INFO - Running setup.py (path:/tmp/pip-iStFSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:44:05 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)) 14:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:44:05 INFO - Running setup.py (path:/tmp/pip-oVEUtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:44:05 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)) 14:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:44:05 INFO - Running setup.py (path:/tmp/pip-apNXyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:44:05 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)) 14:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:44:05 INFO - Running setup.py (path:/tmp/pip-_ldR2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:44:05 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)) 14:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:44:05 INFO - Running setup.py (path:/tmp/pip-EvsPIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:44:05 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)) 14:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:44:05 INFO - Running setup.py (path:/tmp/pip-Qd9mc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:44:05 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)) 14:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:44:05 INFO - Running setup.py (path:/tmp/pip-AkDWNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:44:05 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)) 14:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:44:05 INFO - Running setup.py (path:/tmp/pip-jLMtcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:44:05 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)) 14:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:44:05 INFO - Running setup.py (path:/tmp/pip-49Vm2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:44:05 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)) 14:44:05 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)) 14:44:05 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)) 14:44:05 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)) 14:44:05 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)) 14:44:05 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)) 14:44:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:07 INFO - Downloading blessings-1.6.tar.gz 14:44:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:44:07 INFO - Installing collected packages: blessings 14:44:07 INFO - Running setup.py install for blessings 14:44:07 INFO - Successfully installed blessings 14:44:07 INFO - Cleaning up... 14:44:07 INFO - Return code: 0 14:44:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:44:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:44:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:44:08 INFO - Reading from file tmpfile_stdout 14:44:08 INFO - Current package versions: 14:44:08 INFO - argparse == 1.2.1 14:44:08 INFO - blessings == 1.6 14:44:08 INFO - blobuploader == 1.2.4 14:44:08 INFO - docopt == 0.6.1 14:44:08 INFO - functools32 == 3.2.3-2 14:44:08 INFO - jsonschema == 2.5.1 14:44:08 INFO - manifestparser == 1.1 14:44:08 INFO - mozInstall == 1.12 14:44:08 INFO - mozcrash == 0.17 14:44:08 INFO - mozdebug == 0.1 14:44:08 INFO - mozdevice == 0.48 14:44:08 INFO - mozfile == 1.2 14:44:08 INFO - mozhttpd == 0.7 14:44:08 INFO - mozinfo == 0.9 14:44:08 INFO - mozleak == 0.1 14:44:08 INFO - mozlog == 3.4 14:44:08 INFO - moznetwork == 0.27 14:44:08 INFO - mozprocess == 0.23 14:44:08 INFO - mozprofile == 0.28 14:44:08 INFO - mozrunner == 6.12 14:44:08 INFO - mozscreenshot == 0.1 14:44:08 INFO - mozsystemmonitor == 0.3 14:44:08 INFO - moztest == 0.7 14:44:08 INFO - mozversion == 1.4 14:44:08 INFO - psutil == 3.1.1 14:44:08 INFO - requests == 1.2.3 14:44:08 INFO - wsgiref == 0.1.2 14:44:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:44:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:44:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:08 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 0x7f46ab56ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2135ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132f60>, '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 0x2133d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21341d0>, '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': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', '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 14:44:08 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 14:44:08 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 14:44:08 INFO - Using env: {'DISPLAY': ':0', 14:44:08 INFO - 'HOME': '/home/cltbld', 14:44:08 INFO - 'LANG': 'en_US.UTF-8', 14:44:08 INFO - 'LANGUAGE': 'en_US:en', 14:44:08 INFO - 'LOGNAME': 'cltbld', 14:44:08 INFO - 'MAIL': '/var/mail/cltbld', 14:44:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:08 INFO - 'MOZ_NO_REMOTE': '1', 14:44:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:44:08 INFO - 'NO_EM_RESTART': '1', 14:44:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:44:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:08 INFO - 'PWD': '/builds/slave/test', 14:44:08 INFO - 'SHELL': '/bin/bash', 14:44:08 INFO - 'SHLVL': '1', 14:44:08 INFO - 'TERM': 'linux', 14:44:08 INFO - 'TMOUT': '86400', 14:44:08 INFO - 'USER': 'cltbld', 14:44:08 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', 14:44:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:08 INFO - '_': '/tools/buildbot/bin/python'} 14:44:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:08 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)) 14:44:08 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)) 14:44:08 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)) 14:44:08 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)) 14:44:08 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)) 14:44:08 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)) 14:44:08 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)) 14:44:08 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)) 14:44:08 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)) 14:44:08 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)) 14:44:08 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)) 14:44:08 INFO - Cleaning up... 14:44:08 INFO - Return code: 0 14:44:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:44:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:44:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f46ab56ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2135ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132f60>, '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 0x2133d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21341d0>, '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': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', '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 14:44:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:44:08 INFO - Using env: {'DISPLAY': ':0', 14:44:08 INFO - 'HOME': '/home/cltbld', 14:44:08 INFO - 'LANG': 'en_US.UTF-8', 14:44:08 INFO - 'LANGUAGE': 'en_US:en', 14:44:08 INFO - 'LOGNAME': 'cltbld', 14:44:08 INFO - 'MAIL': '/var/mail/cltbld', 14:44:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:08 INFO - 'MOZ_NO_REMOTE': '1', 14:44:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:44:08 INFO - 'NO_EM_RESTART': '1', 14:44:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:44:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:08 INFO - 'PWD': '/builds/slave/test', 14:44:08 INFO - 'SHELL': '/bin/bash', 14:44:08 INFO - 'SHLVL': '1', 14:44:08 INFO - 'TERM': 'linux', 14:44:08 INFO - 'TMOUT': '86400', 14:44:08 INFO - 'USER': 'cltbld', 14:44:08 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', 14:44:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:08 INFO - '_': '/tools/buildbot/bin/python'} 14:44:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:44:08 INFO - Cleaning up... 14:44:08 INFO - Return code: 0 14:44:08 INFO - Running post-action listener: _resource_record_post_action 14:44:08 INFO - Running post-action listener: _start_resource_monitoring 14:44:08 INFO - Starting resource monitoring. 14:44:08 INFO - [mozharness: 2016-09-20 21:44:08.555470Z] Finished create-virtualenv step (success) 14:44:08 INFO - [mozharness: 2016-09-20 21:44:08.555965Z] Running install step. 14:44:08 INFO - Running pre-action listener: _resource_record_pre_action 14:44:08 INFO - Running main action method: install 14:44:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:44:08 INFO - Reading from file tmpfile_stdout 14:44:08 INFO - Detecting whether we're running mozinstall >=1.0... 14:44:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:44:08 INFO - Reading from file tmpfile_stdout 14:44:08 INFO - Output received: 14:44:08 INFO - Usage: mozinstall [options] installer 14:44:08 INFO - Options: 14:44:08 INFO - -h, --help show this help message and exit 14:44:08 INFO - -d DEST, --destination=DEST 14:44:08 INFO - Directory to install application into. [default: 14:44:08 INFO - "/builds/slave/test"] 14:44:08 INFO - --app=APP Application being installed. [default: firefox] 14:44:08 INFO - mkdir: /builds/slave/test/build/application 14:44:08 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'] 14:44:08 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 14:44:24 INFO - Reading from file tmpfile_stdout 14:44:24 INFO - Output received: 14:44:24 INFO - /builds/slave/test/build/application/firefox/firefox 14:44:24 INFO - Running post-action listener: _resource_record_post_action 14:44:24 INFO - [mozharness: 2016-09-20 21:44:24.794957Z] Finished install step (success) 14:44:24 INFO - [mozharness: 2016-09-20 21:44:24.795200Z] Running run-tests step. 14:44:24 INFO - Running pre-action listener: _resource_record_pre_action 14:44:24 INFO - Running main action method: run_tests 14:44:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:44:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:44:24 INFO - Python 2.7.3 14:44:24 INFO - Return code: 0 14:44:24 INFO - grabbing minidump binary from tooltool 14:44: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')]} 14:44:24 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 0x2132f60>, '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 0x2133d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21341d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:44:24 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 14:44:24 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 14:44:24 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 14:44:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:44:24 INFO - Return code: 0 14:44:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:44:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:44:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:44:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:44:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:44:24 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/1474391126/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 14:44:24 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/1474391126/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 14:44:24 INFO - Using env: {'DISPLAY': ':0', 14:44:24 INFO - 'HOME': '/home/cltbld', 14:44:24 INFO - 'LANG': 'en_US.UTF-8', 14:44:24 INFO - 'LANGUAGE': 'en_US:en', 14:44:24 INFO - 'LOGNAME': 'cltbld', 14:44:24 INFO - 'MAIL': '/var/mail/cltbld', 14:44:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:44:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:44:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:24 INFO - 'MOZ_NO_REMOTE': '1', 14:44:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:44:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:44:24 INFO - 'NO_EM_RESTART': '1', 14:44:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:44:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:44:24 INFO - 'PWD': '/builds/slave/test', 14:44:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:44:24 INFO - 'SHELL': '/bin/bash', 14:44:24 INFO - 'SHLVL': '1', 14:44:24 INFO - 'TERM': 'linux', 14:44:24 INFO - 'TMOUT': '86400', 14:44:24 INFO - 'USER': 'cltbld', 14:44:24 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308', 14:44:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:24 INFO - '_': '/tools/buildbot/bin/python'} 14:44:24 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/1474391126/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 14:44:25 INFO - mozversion application_buildid: 20160920100526 14:44:25 INFO - mozversion application_changeset: e7108b1c6731f1456ee875ed32e469c5eb7df1c1 14:44:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:44:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:44:25 INFO - mozversion application_name: Firefox 14:44:25 INFO - mozversion application_remotingname: firefox-dev 14:44:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:44:25 INFO - mozversion application_vendor: Mozilla 14:44:25 INFO - mozversion application_version: 51.0a2 14:44:25 INFO - mozversion platform_buildid: 20160920100526 14:44:25 INFO - mozversion platform_changeset: e7108b1c6731f1456ee875ed32e469c5eb7df1c1 14:44:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:44:25 INFO - mozversion platform_version: 51.0a2 14:44:25 INFO - using testdate: 1474407865 14:44:25 INFO - actual date: 1474407865 14:44:25 INFO - starting webserver on 'localhost:45825' 14:44:25 INFO - SUITE-START | Running 2 tests 14:44:25 INFO - TEST-START | dromaeo_css 14:44:25 INFO - operating with platform_type : linux_ 14:44:25 INFO - Initialising browser for dromaeo_css test... 14:44:25 INFO - TEST-INFO | started process 24672 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFcW1lU/profile http://localhost:45825/getInfo.html) 14:44:29 INFO - PROCESS | 24672 | __metrics Screen width/height:1600/1200 14:44:29 INFO - PROCESS | 24672 | colorDepth:24 14:44:29 INFO - PROCESS | 24672 | Browser inner width/height: 1024/697 14:44:29 INFO - PROCESS | 24672 | __metrics 14:44:30 INFO - PROCESS | 24672 | 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" 14:44:30 INFO - PROCESS | 24672 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:44:30 INFO - PROCESS | 24672 | console.error: PushService: 14:44:30 INFO - PROCESS | 24672 | stateChangeProcessEnqueue: Error transitioning state 14:44:30 INFO - PROCESS | 24672 | UnknownError 14:44:31 INFO - TEST-INFO | 24672: exit 0 14:44:31 INFO - Browser initialized. 14:44:31 INFO - Running cycle 1/1 for dromaeo_css test... 14:44:31 INFO - TEST-INFO | started process 24762 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFcW1lU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:44:43 INFO - PROCESS | 24762 | RSS: Main: 144449536 14:44:43 INFO - PROCESS | 24762 | 14:44:46 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.13321 seconds. 14:45:31 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.03383 seconds. 14:45:34 INFO - PROCESS | 24762 | 1474407934123 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] 14:45:37 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01686 seconds. 14:45:42 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.04963 seconds. 14:45:55 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01721 seconds. 14:45:57 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 14:45:59 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.16645 seconds. 14:46:08 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 14:46:10 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00069 seconds. 14:46:14 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01696 seconds. 14:46:16 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.16677 seconds. 14:46:27 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.04938 seconds. 14:46:46 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:47:03 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 14:47:22 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 14:47:29 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01692 seconds. 14:47:37 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 14:47:43 INFO - PROCESS | 24762 | Cycle 1(1): loaded http://localhost:45825/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45825/tests/dromaeo/cssquery-ext.html) 14:47:44 INFO - PROCESS | 24762 | RSS: Main: 243433472 14:47:44 INFO - PROCESS | 24762 | 14:47:49 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:48:56 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01661 seconds. 14:49:08 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 14:49:14 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01614 seconds. 14:49:28 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:49:40 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00058 seconds. 14:50:12 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 14:50:14 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00044 seconds. 14:50:20 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 14:50:39 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 14:50:45 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:50:47 INFO - PROCESS | 24762 | Cycle 1(1): loaded http://localhost:45825/tests/dromaeo/cssquery-ext.html (next: http://localhost:45825/tests/dromaeo/cssquery-jquery.html) 14:50:47 INFO - PROCESS | 24762 | RSS: Main: 234762240 14:50:47 INFO - PROCESS | 24762 | 14:50:52 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00047 seconds. 14:50:54 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01402 seconds. 14:50:58 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 14:51:14 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 14:51:18 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00048 seconds. 14:51:34 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00052 seconds. 14:51:50 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00074 seconds. 14:52:12 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00034 seconds. 14:52:18 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0171 seconds. 14:52:26 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00027 seconds. 14:52:49 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00035 seconds. 14:52:57 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00015 seconds. 14:53:13 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00065 seconds. 14:53:33 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 14:53:49 INFO - PROCESS | 24762 | Cycle 1(1): loaded http://localhost:45825/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45825/tests/dromaeo/cssquery-mootools.html) 14:53:50 INFO - PROCESS | 24762 | RSS: Main: 175800320 14:53:50 INFO - PROCESS | 24762 | 14:53:53 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01691 seconds. 14:53:55 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.03335 seconds. 14:54:02 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0006 seconds. 14:54:06 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00014 seconds. 14:54:49 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00081 seconds. 14:54:51 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 14:54:57 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0008 seconds. 14:55:19 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00034 seconds. 14:55:45 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 14:56:00 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0005 seconds. 14:56:17 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00014 seconds. 14:56:25 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00022 seconds. 14:56:52 INFO - PROCESS | 24762 | Cycle 1(1): loaded http://localhost:45825/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45825/tests/dromaeo/cssquery-prototype.html) 14:56:52 INFO - PROCESS | 24762 | RSS: Main: 175034368 14:56:52 INFO - PROCESS | 24762 | 14:57:07 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00028 seconds. 14:57:08 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 2.01722 seconds. 14:57:21 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 14:58:14 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 14:58:18 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 14:58:36 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:58:42 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 14:58:47 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01683 seconds. 14:58:54 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00014 seconds. 14:59:00 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 14:59:16 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01682 seconds. 14:59:55 INFO - PROCESS | 24762 | Cycle 1(1): loaded http://localhost:45825/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45825/tests/dromaeo/cssquery-yui.html) 14:59:55 INFO - PROCESS | 24762 | RSS: Main: 174964736 14:59:55 INFO - PROCESS | 24762 | 14:59:59 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01678 seconds. 15:00:03 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01644 seconds. 15:00:06 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00065 seconds. 15:00:09 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 15:00:14 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01697 seconds. 15:00:20 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 15:00:22 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 15:00:26 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00076 seconds. 15:00:28 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 15:00:40 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0005 seconds. 15:01:03 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01661 seconds. 15:01:13 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00065 seconds. 15:01:47 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01644 seconds. 15:01:49 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:02:03 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00069 seconds. 15:02:25 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00064 seconds. 15:02:36 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01633 seconds. 15:02:46 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00041 seconds. 15:02:58 INFO - PROCESS | 24762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01679 seconds. 15:02:58 INFO - PROCESS | 24762 | Cycle 1(1): loaded http://localhost:45825/tests/dromaeo/cssquery-yui.html (next: http://localhost:45825/tests/dromaeo/cssquery-dojo.html) 15:02:58 INFO - PROCESS | 24762 | RSS: Main: 253841408 15:02:58 INFO - PROCESS | 24762 | 15:02:58 INFO - PROCESS | 24762 | __start_tp_report 15:02:58 INFO - PROCESS | 24762 | _x_x_mozilla_page_load 15:02:58 INFO - PROCESS | 24762 | _x_x_mozilla_page_load_details 15:02:58 INFO - PROCESS | 24762 | |i|pagename|runs| 15:02:58 INFO - PROCESS | 24762 | |0;dojo.html;3074.5234488654255;3116.392303500817;3134.2321131322824;3151.5902932618756;3166.3983843069827;5593.888122237558;5680.31836179658;5682.147677848319;5687.17535957286;5701.287339082614;5638;5711.371749107595;5715;5720.11338242572;5746.339170995327;5135.666181698187;5151.5106064923775;5163;5201.765920533585;5226.5557427618505;5027.974860125694;5248.5538729208065;5251.579873610102;5257.13257312543;5272.551833094191;5018.673786203885;5093.949060509403;5114.130597798383;5122.897542049157;5138.023775482665;5171.637985341028;5195.688258704471;5199.844004679866;5256.08018596744;5268.947310526923;5105.259737338102;5143.7685304161405;5217.295665085241;5221.973890130563;5248.8425347239645;5419.864503387407;5454.236406903036;5483.890322193533;5504.2018907258625;5510.1459273812625;5900.527957763412;5927.199828023205;5948.107783832416;6012.068129439944;6022.337542870281;6056.666883321415;6057.545684073712;6060.878782424371;6069.028955367165;6070.939290607126;5413.621046526745;5437.945620543823;5527.088030474967;5567.972160139255;5592.720363981866;6128.080787881764;6196.504279657616;6197.1324014637985;6198.969005154945;6199.194104766351;6008.098785182257;6019.759209631653;6022.036474164201;6027.939720602738;6029.698515074228;5851.38560451155;5894.2926848778425;5976;6033.064874944391;6040.637561746309;6007.459328660441;6013.639181649028;6013.639181649115;6018.037114061816;6052.12244224585;6019.067044608093;6055.455009049207;6061.090836374579;6078.665673388006;6083.270007599028;4490.281554951192;4495.303227999665;4561.452625684873;4601.217792975202;4645.6980296280635;4085.387191921176;4109.321961876317;4122.484689413824;4124.6906482013965;4145;5749.597528172988;5759.1937128802;5760.279965004374;5762.596618236683;5769.480746732815;5816.854578635568;5872.677002764804;5965.463108320272;6073.848153796191;6093.573449858467;5675.4892059714;5737.770489180386;5739;5762.884742305214;5777.740001699934;5224.738763061908;5232.686038837682;5234.031704134671;5236.293100431393;5237.450067742909;6082.118092876508;6130.417610327012;6180.5784669526265;6232.158658581034;6239.96880015597;5913.408659134224;5924.644521328734;5941.37615550361;6028.914795336881;6066.939330606638;6175.752969881154;6175.876482470415;6187.81436556904;6188.783392581238;6191.9690401547705;5796.94203057964;5875.618084824473;5876.147958545901;5881.264841894763;5909.940900590939;5840.649561026352;5981.671008094597;5996.520278377807;6013;6132.540059495466;5890.4698577128265;5933.2880054393745;5943.227380440688;5946.0189068803165;5947.494462970696;5943.762249509971;5945.137954996588;5949.52403807703;5955.821325360246;5957.880842383041;5597.63615365;5712.0860662293835;5757.769689212552;5763.164341170591;5813.447722466359;5958.7616495341435;5974.522038237017;5979.1928089707335;5980.671063091398;6006.87986240264;5604.131359639263;5656.886862262814;5803.767849285981;5805.10020946754;5813.476787089182 15:02:58 INFO - PROCESS | 24762 | |1;ext.html;593.7239183779542;626.9561130720848;638.3488841381787;649.0912722188934;655.9930506672259;34613.480797788055;35368.46947295789;38022.42966355507;38051.238975220476;38065.23869522608;48127.278090828666;49590;49728.75135624326;53803.46196538033;53825;9026.27789776818;9156.633734650606;9182.586783594721;9192.908070919271;9200.907990920106;8255.215754503313;9166.54167291636;9264.861027084566;9291.303152263574;9311.95344023279;8516.914830851674;10980.945095274512;11188.66434006981;11230.606928757496;11575.710607234845;10969.23215374924;11044.226904116675;11058.170637202242;11078.723031924186;11134.053605443547;11035.062019728333;11092.389918554476;11093.3898635575;11108.16688748339;11108.777824443547;46858.5314146861;47164.292535611996;47403.28895066577;47458.52541474575;47656.76171619154;3799.67702745267;3830.751001184914;3865.922681546381;3869.8839034829;3882.8835134946;35586.46620300672;35932.64067359345;35968;36087.458688119696;37803.621963780286;4446.177457170402;4452;4521.553103007039;4544.182047231497;4627.467841198271;362448;363045;363946;364416;366130;38979;66821.6658916712;67266.66366668136;67424;67448.66275668688;40201;42873.142537149084;42882.35676464855;42886.57113428826;42926.57073429288;17376.131193440277;17479.737803932952;17551.91224043897;17564.209610567556;17568.824311756718;14498.565043048726;14551.490697825526;14635.560933172022;14640.487582934546;14649.267536623127;9052.683156089637;9223.44659320443;9234;9670.468124253233;9674.854877176847;3479.304139172176;3574.1779390740276;3686.318031164243;3709.258148370283;3735.9252814943547;1021.4790456867051;1060.9787804244027;1075.860138182047;1174.2191442690687;1222.5782105173878;35298.64701352954;35510;35514.28971420557;35631.82184089063;35749.64250357515;1101.388729255255;1191.755690083531;1192.791261529229;1228.7173949991552;1266.3351740336213;18578.163982620816;18619.534511637048;18666.533336666693;18670.159842807105;18904.338348157748;569272;573519;576657;577153;578035;55519;56594.71702641543;64845.67577162084;65632.67183664051;68297.3170268291;1396.1832328087978;1448.5943935698022;1448.8478709735537;1455.7961885336063;1458.358322338168;34234.828825856704;36779.44830827593;36923.261534768615;37005.81497092497;37152;53073.734631326595;53297;53355;53425.73287133539;53520.73239633933;454642;542447;553169;553297;893815;48062;48318;49037.2644410327;49309;49674.75162624164;52939.47060529499;53142;53315.73342133265;53414.465855340946;53464;44111;45298.54701453075;45312.773436133924;46495.30257046079;47500.7624961873;44890.326645099696;45165.774171128935;46388.536114638424;48813.75593122012;50007.74996124996;44950.55049449464;45651.771741141085;45873.31190032219;45976.540234597225;46128.53871461243;37669.62330376661;39447.605523944396;40350.59649403586;40392.59607404006;40721.38917916174;29237.41525169527;30285.731424458412;30308.696913030588;30525.389492210477;30592 15:02:58 INFO - PROCESS | 24762 | |2;jquery.html;401.4820881063428;436.37816112040343;443.5010613060307;445.43429844097994;449.41351536245185;18129;20385.18459261628;20407.285744998928;21121.15515379383;22256.55486890261;20727.170913163493;20750.377488675364;20767.27314543991;20875.686864697043;20928.686069708965;5623.26897503325;6024.698765061729;6058.303295121052;6060.666663333515;6069.57512974092;5940.910886336708;5975;5978.671173085479;6074.058520929263;6074.969625151868;5937.198478205431;5989.700514974255;5992.880142397149;5992.940070599282;5995.790147344844;5786.045302525057;5946.910796338058;5975.940240597582;5985.281766188064;5991.670458124801;5374.677519348852;5397.757100930428;5416.593755468316;5419.701916394597;5420.91868621971;24390.39024024396;24410.877945610337;24775.752242477705;24843.378915527075;25032.749672503225;2479.690038745157;2503.7120731115974;2525.10358822619;2571.7813985811226;2644.576867701178;15022.173780442072;15050.774238386324;15077.396904123822;15692.372305107781;16019.279132439067;1981.8711783734154;2007.4479518132484;2015.4256037029522;2026.280670362015;2154.4721543222013;61562.38437615656;66199.66900165516;80619.5969020157;82965.58517207435;87508.5624571873;10520.684379468628;10768.56925722978;10858;11027.61403350879;11562.135096087148;16749.91625041867;16751.413700520425;16773.83226167747;16778.496645100666;16795.328186872634;8666.959964804264;8843.557822108998;8962.193402593797;8994.010658827523;9005.909940900638;8543.658253669784;8658.610362533702;8742.606582703793;8792.648294068165;8818.382713210014;6903.206131294965;6973.721051157999;7123.216446190915;7167.856642867114;7191.964040179766;2091.309867743641;2099.5276062885728;2101.8633788803677;2121.4619400934507;2123.61774880523;978.5351957819978;1007.0483393193508;1013.2451323763843;1023.0690072034413;1111.9332840029624;20658.380248592566;21584.78415215859;21662.675059874113;21744.238951636933;21809.56380872374;1031.3914790273775;1031.7936412717486;1050.8633877595864;1058.2539309786582;1094.578587243917;11030.448477576088;12054.035677145806;12157.452914618863;12180.330081845583;12309.076819238593;433903;441260;445673;451065;453754;81507.59246203731;82500.58749706214;83780.58109709412;84493.57753211194;84937.57531212427;1083.7940791249796;1134.5405110929921;1155.2952698853692;1155.3299086529792;1176.3588844079998;18399.632007360047;18419.26322947067;18843.15205815769;18912.0932009547;19009.904950475713;42654.78672606617;43069.92325191895;43187;43482.47811326588;43902.12195756131;56067;56689.43310566842;57367.139492906805;58392;58520;8361.581920903809;8614.65541378338;8792.296616270554;8870.379073464796;8907.421017633833;11583.884161158281;12435.564755233523;12706.872931270938;12716.427760750603;12723.618291451272;8984.505852178192;9078.31912606544;9578.521073946136;9975.900240997787;11348.602798901999;8889.955550222467;9103.954480227556;9302.906970930204;9708;9726.124648781644;9310.581023854078;9314.580843861906;9447.401964459828;9598.679336286992;10140.391576505433;13580.117292375964;13809.378577963815;13848.515301964382;13985.720285594027;14056.648583785487;13364.799528007283;13550.59348219555;13703;13742;13790.172589644653 15:02:58 INFO - PROCESS | 24762 | |3;mootools.html;1382.7096309774947;1516.4313382481573;1557.9844201557985;1709.7093494106;1821.5537193387622;8142.796430089244;8835.204831565166;9111.407758495694;9123.498207598588;9454;9186.448813071203;9252.768680782983;9270.67552635658;9271.258299336054;9290.442573445598;2536.9365765855864;2566.1916496303697;2569.8843552040203;2570.627259047437;2570.897164113433;2468.90124395024;2528.203615861007;2530.8987640494356;2534.6071358939394;2537.251510804308;2500.9124680636182;2527.317624241454;2533.645289659449;2534.303066656675;2534.3157347516158;2448.424620214257;2473.183849329726;2475.7152927413217;2482.044412901037;2489.103922587867;2410.7348191698898;2439.926802195937;2451.705795304557;2458.311672731638;2460.815438842076;9152.176304132594;9213.30900182489;9366.34435589509;9401.858972115424;9404.106609872051;5582.944170558283;5801.941980580183;5806.82579522615;5828.358880523139;5836.503897168745;3750.4374343848367;3750.924981500382;3830.9808450957844;3833.17586718854;3855.1904100139004;1157.70632550394;1184.8281999110081;1187.145255416099;1217.2878865863481;1217.738186289943;12707.618771436872;12755.744885102245;12766.170198937132;12805.679858003532;12879.098463107586;4757.09615173116;4785.49752276013;4912.975435122801;4935.876603084952;4979.45226025137;10260.127889129508;10275.075243227995;10285.074343308988;10319.690409287734;10336.689899303034;5633.746481408347;5651.717414129277;5675.716214189274;5683.062294721407;5683.403242659544;4464.15181115587;4529.184746745552;4582.908341833145;4667;5292.52367286946;3970.4242884781543;4051.3517837145914;4174.519930208065;4185.874423767292;4186.392973018956;1232.2544860359533;1236.5857437758273;1244.5519612939336;1245.6574442028334;1267.258653687603;993.4387071304834;1003.2826529031777;1014.898510148994;1016.7813920007234;1030.0111892583204;6130.846728831815;6134.78528251509;6144.354842741448;6301.054841773771;6555.541112122201;479.4989236248129;491.64847134299146;492.7240745182709;496.9204927211701;540.8945255675184;4347.608715215646;4468;4473.261911784519;4482.843100491467;4552.362669226311;11259.774804503864;11784;11936.820947685792;12042.09684273683;12044.337561434206;11877.58428455;11924;11945.522179112739;11954.521819127138;11975.101867359808;7855;8204.548749818818;8205.097439281673;8908.8663670045;8997.325200609981;6259.561830671809;6373.904391434225;6434.2922278548385;6539;6558.770443034471;10434.37393756377;10552.630657926935;10615.8938410618;10754.677359679223;10863.67408977732;10961.780764384826;10995.945020274847;11034.834477483004;11058.723531911766;11086.113110950942;8431.536265505456;8532.488050716976;8663.523506207219;8732.388732788899;8738.169873861973;8984.371094023356;9091.681791137278;9115.316351273548;9211.447313161232;9296.209822164921;7878.4091193161885;7899.407544434305;7946;8052.154523775153;8161.632726527202;7617.771466855782;7698.538087714755;7707.537547747153;7877.251661092187;8161.673533058611;7232.0959880015;7234.782956511102;7268.236835132235;7301.890471643036;7312.817179570553;5167.22491626259;5268.236105764566;5302.28419163408;5347.625666203328;5349.893002140013;5313.335833020872;5541.251930989265;5564.721763911707;5565.220869078251;5629.971850140723 15:02:58 INFO - PROCESS | 24762 | |4;prototype.html;357.93557159711247;364.3860095738681;366.6260414377337;368.32780176178466;369.6432942210766;5608.43915608439;5642.492175704186;5650.180723795047;5667.931205110136;5701.714914254281;5227.634065615409;5535.308086489189;5538.47384498472;5541.861453463655;5602.635828671134;3848.2111167210796;4295.699301048928;4379.277419225824;4391.0559229765595;4402.339649052652;4382.312809568307;4385.671074669397;4388.4201687392515;4388.451443569554;4401.427814384126;4145.523264824538;4375.387445757597;4382.583654552828;4384.649228061748;4393.406890069848;4206.516250631171;4268.765217913014;4364.214441400547;4365.3451982202605;4375.343698445226;3893.552241492236;3896.3181443247317;3905.3165696003084;3905.7265991380614;3910.5502867170353;5228.843134705927;5411.377691565481;5432;5434.429384914567;5435.673859568438;4921.8523444296725;4953.356063711694;4975.875603109951;4983.377077865267;5015.14742493777;5824.679642619654;5828.601135727437;5829.795957141479;5839.328477225131;5853.795117170921;3973.006748312922;4239.47006624172;4260.893477663083;4265.786710664454;4272.978635106804;5763.711814409305;5892.410758924073;6004.42957918997;6076.574639775217;6096.4817990470865;4195.328747400462;4338.394793926267;4347.869563913087;4351.10802285537;4353.760543170093;4386.517483076859;4397.846075387346;4404.273294906368;4413.44831896013;4416.80124394403;4902;5017.89964200714;5036.143855544567;5044.142495775727;5051.368578927634;5874.267091208104;5885.882282354329;5889.9692553802915;5900.822975310747;5918.615290006136;1489.9925500372647;1514.1672080355982;1518.2484670088377;1529.7093552225042;1530.1737061986403;921.861720741894;969.0842154164247;982.9803403931882;989.3922376138775;989.5101924547395;5018.1647369237;5040.949590504048;5043.773030213649;5058.873528161752;5059.797608095709;5274.4198138204765;5290.841274761763;5298.337707786527;5298.523132918056;5310.097283461821;4832.51674832521;4907.975460122677;4988.6757360771435;5028.84913452597;5073.543381095646;2829.929251768722;2832.7535884210884;2846.84342361168;2859.542473204319;2863.642044744407;5502.669839809624;5518.751656175482;5521.420250873681;5523.28197334344;5525.944740552623;5379.520631826201;5706.514946229534;6173.197484327098;6215.192025036717;6231.532635052389;6224.502039836802;6238.875222495525;6253.343398943228;6266.308096813835;6279.183706118267;5879.441453061952;6086.756529738938;6088.178095956989;6096.634201947897;6116.3272040076445;5937.287525496968;5953.047512397996;6013.639181649115;6042.214512113397;6045.123457098608;5919.348871624204;6022.849428764316;6034.818955431344;6109.602875813058;6223.96888015557;4253.829846806093;4380.561943805594;4397.428334316518;4401.757903315349;4403.471583410012;5875;6039.15451836752;6044.214252147192;6044.335123136363;6063.333033366238;5812.505936995402;5866.41335866427;5893.705314734332;5896.3514013457625;5902.1441890926435;5820.272465941757;5834.432849546186;5867.442592953663;5874.383189765184;5875.882482350415;5681.801136960186;5783.768649253982;5818.50542703858;5827.679477628772;5831.79588714393;5880.353161152356;5886.117082437669;5898.675572843535;5905.72232411016;5922.703864806828;5456.399796022513;5736.971315143398;5751.396103409082;5754.251947246831;5759 15:02:58 INFO - PROCESS | 24762 | |5;yui.html;765.3762183820186;807.0396228488095;826.330672155554;827.5407149032286;904.9140331668493;16740;17703.645927081452;17720.556986075353;17737.02446365451;17763.733543996834;15599.84400155998;16741;17340.132993350344;17367.218475168615;17369.652606947853;723.1611330856208;745.4483682075279;849.1932663969224;850.0649285785648;876.3077169036453;860.4020205956863;879.7668617816252;880.4453194487496;880.6653471680785;880.770999540121;929.6792606550763;930.014184963937;930.1117432851644;930.1953809954374;930.5440334236209;759.9772006839776;783.1502819440914;883.8099808541194;924.2098006204699;924.6440120553601;669.4109183918121;673.6766352150995;673.7473447457204;673.8281738156787;673.8787018336698;17440;17521.211545480484;17554.21006054718;17561.648767024715;17563.385281515086;15862.682746345008;15944.202789860461;15954.28205730745;16147.515574532783;16251.593710157222;14543.127412355187;14625.634359141;14680.853191468163;14842.851571484363;14923.925380373137;4795.976020119877;4801.591864691505;4817.686850354716;4860.781264843091;4861.270809378586;73636.26363736401;91323;91380.54309728475;91543;91745;13033.869661303266;13245.933770331087;13275;13381.063325567116;13542.59372218835;25001.624975625382;25014.874925625256;25179.748202518105;25283;25625.487490250092;9564.330496865292;9818.80362392748;9867.259955503367;9885.555150018266;9972.501374931224;4889.926651100236;5157.07172708916;5215.243789650555;5233.371995360581;5240;3820.84716611338;4032.818523166406;4044.373122166069;4062.370332598452;4063.6139566741113;659.7328082126746;667.6861874918781;670.7350596514447;671.5030877150873;672.8284287506655;599.4185639929261;687.4225650453643;734.1593875013081;746.3767753925424;749.3443237167478;10354.223433242538;10367.533460994273;10399.376037437627;10791.946040269748;10882.755725267152;581.1152445899178;736.8137298948795;746.1344839985699;754.2306847016013;759.6809340077181;4840.273958906192;5033.723145227048;5035.219540971156;5079.339685840817;5081.390233172043;75898.62050689712;77584.2241577577;111960;133533.33233333772;134816;86818.13181868226;113723.43138284255;118678.4066079664;119627;120154;57545.424545754846;78573;90952.99999999868;94407.52796235975;96081.51959240298;10400.427976461368;10502.119529524127;10508.894911050791;10622.256442048982;10790.244682872124;53264.73367633137;54238.72880635571;54274.72862635661;54658.7267063678;55302.72348638231;72189;81152;82329.17670823455;82547;82628;13788.793168102286;14516.637084072983;14693;14711.485098021518;14823.925880370529;14545.418183272974;14685.55943321702;14857.628559286104;14980.475683351031;16133.677326453171;11994.520219191134;14417.567472975827;15091.245437728288;15105.622359441102;15964.12197329158;14561.635959101108;15314.157721324988;15374.846251537341;15914.442994495137;16713.247903844112;14590.708185836436;14636.195009274592;15479;15770.132642704302;16129.516114516584;11789.646310610695;12082.879171208175;12278.81581776292;12733.29966851795;13315.334233288491;12580.685482862635;12820.207552036798;12859.549915752903;12882.67793305175;12906.483740650645 15:02:58 INFO - PROCESS | 24762 | __end_tp_report 15:02:58 INFO - PROCESS | 24762 | __start_cc_report 15:02:58 INFO - PROCESS | 24762 | _x_x_mozilla_cycle_collect,894 15:02:58 INFO - PROCESS | 24762 | __end_cc_report 15:02:58 INFO - PROCESS | 24762 | __startTimestamp1474408978884__endTimestamp 15:02:58 INFO - PROCESS | 24762 | 15:02:58 INFO - PROCESS | 24762 | ------- Summary: start ------- 15:02:58 INFO - PROCESS | 24762 | Number of tests: 6 15:02:58 INFO - PROCESS | 24762 | 15:02:58 INFO - PROCESS | 24762 | [#0] dojo.html Cycles:165 Average:5592.13 Median:5803.77 stddev:642.83 (11.1%) stddev-sans-first:613.71 15:02:58 INFO - PROCESS | 24762 | Values: 3074.5 3116.4 3134.2 3151.6 3166.4 5593.9 5680.3 5682.1 5687.2 5701.3 5638.0 5711.4 5715.0 5720.1 5746.3 5135.7 5151.5 5163.0 5201.8 5226.6 5028.0 5248.6 5251.6 5257.1 5272.6 5018.7 5093.9 5114.1 5122.9 5138.0 5171.6 5195.7 5199.8 5256.1 5268.9 5105.3 5143.8 5217.3 5222.0 5248.8 5419.9 5454.2 5483.9 5504.2 5510.1 5900.5 5927.2 5948.1 6012.1 6022.3 6056.7 6057.5 6060.9 6069.0 6070.9 5413.6 5437.9 5527.1 5568.0 5592.7 6128.1 6196.5 6197.1 6199.0 6199.2 6008.1 6019.8 6022.0 6027.9 6029.7 5851.4 5894.3 5976.0 6033.1 6040.6 6007.5 6013.6 6013.6 6018.0 6052.1 6019.1 6055.5 6061.1 6078.7 6083.3 4490.3 4495.3 4561.5 4601.2 4645.7 4085.4 4109.3 4122.5 4124.7 4145.0 5749.6 5759.2 5760.3 5762.6 5769.5 5816.9 5872.7 5965.5 6073.8 6093.6 5675.5 5737.8 5739.0 5762.9 5777.7 5224.7 5232.7 5234.0 5236.3 5237.5 6082.1 6130.4 6180.6 6232.2 6240.0 5913.4 5924.6 5941.4 6028.9 6066.9 6175.8 6175.9 6187.8 6188.8 6192.0 5796.9 5875.6 5876.1 5881.3 5909.9 5840.6 5981.7 5996.5 6013.0 6132.5 5890.5 5933.3 5943.2 5946.0 5947.5 5943.8 5945.1 5949.5 5955.8 5957.9 5597.6 5712.1 5757.8 5763.2 5813.4 5958.8 5974.5 5979.2 5980.7 6006.9 5604.1 5656.9 5803.8 5805.1 5813.5 15:02:58 INFO - PROCESS | 24762 | 15:02:58 INFO - PROCESS | 24762 | [#1] ext.html Cycles:180 Average:67760.88 Median:41797.27 stddev:141655.14 (338.9%) stddev-sans-first:141962.75 15:02:58 INFO - PROCESS | 24762 | Values: 593.7 627.0 638.3 649.1 656.0 34613.5 35368.5 38022.4 38051.2 38065.2 48127.3 49590.0 49728.8 53803.5 53825.0 9026.3 9156.6 9182.6 9192.9 9200.9 8255.2 9166.5 9264.9 9291.3 9312.0 8516.9 10980.9 11188.7 11230.6 11575.7 10969.2 11044.2 11058.2 11078.7 11134.1 11035.1 11092.4 11093.4 11108.2 11108.8 46858.5 47164.3 47403.3 47458.5 47656.8 3799.7 3830.8 3865.9 3869.9 3882.9 35586.5 35932.6 35968.0 36087.5 37803.6 4446.2 4452.0 4521.6 4544.2 4627.5 362448.0 363045.0 363946.0 364416.0 366130.0 38979.0 66821.7 67266.7 67424.0 67448.7 40201.0 42873.1 42882.4 42886.6 42926.6 17376.1 17479.7 17551.9 17564.2 17568.8 14498.6 14551.5 14635.6 14640.5 14649.3 9052.7 9223.4 9234.0 9670.5 9674.9 3479.3 3574.2 3686.3 3709.3 3735.9 1021.5 1061.0 1075.9 1174.2 1222.6 35298.6 35510.0 35514.3 35631.8 35749.6 1101.4 1191.8 1192.8 1228.7 1266.3 18578.2 18619.5 18666.5 18670.2 18904.3 569272.0 573519.0 576657.0 577153.0 578035.0 55519.0 56594.7 64845.7 65632.7 68297.3 1396.2 1448.6 1448.8 1455.8 1458.4 34234.8 36779.4 36923.3 37005.8 37152.0 53073.7 53297.0 53355.0 53425.7 53520.7 454642.0 542447.0 553169.0 553297.0 893815.0 48062.0 48318.0 49037.3 49309.0 49674.8 52939.5 53142.0 53315.7 53414.5 53464.0 44111.0 45298.5 45312.8 46495.3 47500.8 44890.3 45165.8 46388.5 48813.8 50007.7 44950.6 45651.8 45873.3 45976.5 46128.5 37669.6 39447.6 40350.6 40392.6 40721.4 29237.4 30285.7 30308.7 30525.4 30592.0 15:02:58 INFO - PROCESS | 24762 | 15:02:58 INFO - PROCESS | 24762 | [#2] jquery.html Cycles:180 Average:27996.46 Median:1523.03 stddev:73287.94 (4812.0%) stddev-sans-first:73464.25 15:02:58 INFO - PROCESS | 24762 | Values: 401.5 436.4 443.5 445.4 449.4 18129.0 20385.2 20407.3 21121.2 22256.6 20727.2 20750.4 20767.3 20875.7 20928.7 5623.3 6024.7 6058.3 6060.7 6069.6 5940.9 5975.0 5978.7 6074.1 6075.0 5937.2 5989.7 5992.9 5992.9 5995.8 5786.0 5946.9 5975.9 5985.3 5991.7 5374.7 5397.8 5416.6 5419.7 5420.9 24390.4 24410.9 24775.8 24843.4 25032.7 2479.7 2503.7 2525.1 2571.8 2644.6 15022.2 15050.8 15077.4 15692.4 16019.3 1981.9 2007.4 2015.4 2026.3 2154.5 61562.4 66199.7 80619.6 82965.6 87508.6 10520.7 10768.6 10858.0 11027.6 11562.1 16749.9 16751.4 16773.8 16778.5 16795.3 8667.0 8843.6 8962.2 8994.0 9005.9 8543.7 8658.6 8742.6 8792.6 8818.4 6903.2 6973.7 7123.2 7167.9 7192.0 2091.3 2099.5 2101.9 2121.5 2123.6 978.5 1007.0 1013.2 1023.1 1111.9 20658.4 21584.8 21662.7 21744.2 21809.6 1031.4 1031.8 1050.9 1058.3 1094.6 11030.4 12054.0 12157.5 12180.3 12309.1 433903.0 441260.0 445673.0 451065.0 453754.0 81507.6 82500.6 83780.6 84493.6 84937.6 1083.8 1134.5 1155.3 1155.3 1176.4 18399.6 18419.3 18843.2 18912.1 19009.9 42654.8 43069.9 43187.0 43482.5 43902.1 56067.0 56689.4 57367.1 58392.0 58520.0 8361.6 8614.7 8792.3 8870.4 8907.4 11583.9 12435.6 12706.9 12716.4 12723.6 8984.5 9078.3 9578.5 9975.9 11348.6 8890.0 9104.0 9302.9 9708.0 9726.1 9310.6 9314.6 9447.4 9598.7 10140.4 13580.1 13809.4 13848.5 13985.7 14056.6 13364.8 13550.6 13703.0 13742.0 13790.2 15:02:58 INFO - PROCESS | 24762 | 15:02:58 INFO - PROCESS | 24762 | [#3] mootools.html Cycles:180 Average:6117.49 Median:4567.64 stddev:3499.32 (76.6%) stddev-sans-first:3491.04 15:02:58 INFO - PROCESS | 24762 | Values: 1382.7 1516.4 1558.0 1709.7 1821.6 8142.8 8835.2 9111.4 9123.5 9454.0 9186.4 9252.8 9270.7 9271.3 9290.4 2536.9 2566.2 2569.9 2570.6 2570.9 2468.9 2528.2 2530.9 2534.6 2537.3 2500.9 2527.3 2533.6 2534.3 2534.3 2448.4 2473.2 2475.7 2482.0 2489.1 2410.7 2439.9 2451.7 2458.3 2460.8 9152.2 9213.3 9366.3 9401.9 9404.1 5582.9 5801.9 5806.8 5828.4 5836.5 3750.4 3750.9 3831.0 3833.2 3855.2 1157.7 1184.8 1187.1 1217.3 1217.7 12707.6 12755.7 12766.2 12805.7 12879.1 4757.1 4785.5 4913.0 4935.9 4979.5 10260.1 10275.1 10285.1 10319.7 10336.7 5633.7 5651.7 5675.7 5683.1 5683.4 4464.2 4529.2 4582.9 4667.0 5292.5 3970.4 4051.4 4174.5 4185.9 4186.4 1232.3 1236.6 1244.6 1245.7 1267.3 993.4 1003.3 1014.9 1016.8 1030.0 6130.8 6134.8 6144.4 6301.1 6555.5 479.5 491.6 492.7 496.9 540.9 4347.6 4468.0 4473.3 4482.8 4552.4 11259.8 11784.0 11936.8 12042.1 12044.3 11877.6 11924.0 11945.5 11954.5 11975.1 7855.0 8204.5 8205.1 8908.9 8997.3 6259.6 6373.9 6434.3 6539.0 6558.8 10434.4 10552.6 10615.9 10754.7 10863.7 10961.8 10995.9 11034.8 11058.7 11086.1 8431.5 8532.5 8663.5 8732.4 8738.2 8984.4 9091.7 9115.3 9211.4 9296.2 7878.4 7899.4 7946.0 8052.2 8161.6 7617.8 7698.5 7707.5 7877.3 8161.7 7232.1 7234.8 7268.2 7301.9 7312.8 5167.2 5268.2 5302.3 5347.6 5349.9 5313.3 5541.3 5564.7 5565.2 5630.0 15:02:58 INFO - PROCESS | 24762 | 15:02:58 INFO - PROCESS | 24762 | [#4] prototype.html Cycles:180 Average:4851.58 Median:5435.05 stddev:1424.08 (26.2%) stddev-sans-first:1387.56 15:02:58 INFO - PROCESS | 24762 | Values: 357.9 364.4 366.6 368.3 369.6 5608.4 5642.5 5650.2 5667.9 5701.7 5227.6 5535.3 5538.5 5541.9 5602.6 3848.2 4295.7 4379.3 4391.1 4402.3 4382.3 4385.7 4388.4 4388.5 4401.4 4145.5 4375.4 4382.6 4384.6 4393.4 4206.5 4268.8 4364.2 4365.3 4375.3 3893.6 3896.3 3905.3 3905.7 3910.6 5228.8 5411.4 5432.0 5434.4 5435.7 4921.9 4953.4 4975.9 4983.4 5015.1 5824.7 5828.6 5829.8 5839.3 5853.8 3973.0 4239.5 4260.9 4265.8 4273.0 5763.7 5892.4 6004.4 6076.6 6096.5 4195.3 4338.4 4347.9 4351.1 4353.8 4386.5 4397.8 4404.3 4413.4 4416.8 4902.0 5017.9 5036.1 5044.1 5051.4 5874.3 5885.9 5890.0 5900.8 5918.6 1490.0 1514.2 1518.2 1529.7 1530.2 921.9 969.1 983.0 989.4 989.5 5018.2 5040.9 5043.8 5058.9 5059.8 5274.4 5290.8 5298.3 5298.5 5310.1 4832.5 4908.0 4988.7 5028.8 5073.5 2829.9 2832.8 2846.8 2859.5 2863.6 5502.7 5518.8 5521.4 5523.3 5525.9 5379.5 5706.5 6173.2 6215.2 6231.5 6224.5 6238.9 6253.3 6266.3 6279.2 5879.4 6086.8 6088.2 6096.6 6116.3 5937.3 5953.0 6013.6 6042.2 6045.1 5919.3 6022.8 6034.8 6109.6 6224.0 4253.8 4380.6 4397.4 4401.8 4403.5 5875.0 6039.2 6044.2 6044.3 6063.3 5812.5 5866.4 5893.7 5896.4 5902.1 5820.3 5834.4 5867.4 5874.4 5875.9 5681.8 5783.8 5818.5 5827.7 5831.8 5880.4 5886.1 5898.7 5905.7 5922.7 5456.4 5737.0 5751.4 5754.3 5759.0 15:02:58 INFO - PROCESS | 24762 | 15:02:58 INFO - PROCESS | 24762 | [#5] yui.html Cycles:180 Average:22303.74 Median:4062.99 stddev:31368.50 (772.1%) stddev-sans-first:31414.80 15:02:58 INFO - PROCESS | 24762 | Values: 765.4 807.0 826.3 827.5 904.9 16740.0 17703.6 17720.6 17737.0 17763.7 15599.8 16741.0 17340.1 17367.2 17369.7 723.2 745.4 849.2 850.1 876.3 860.4 879.8 880.4 880.7 880.8 929.7 930.0 930.1 930.2 930.5 760.0 783.2 883.8 924.2 924.6 669.4 673.7 673.7 673.8 673.9 17440.0 17521.2 17554.2 17561.6 17563.4 15862.7 15944.2 15954.3 16147.5 16251.6 14543.1 14625.6 14680.9 14842.9 14923.9 4796.0 4801.6 4817.7 4860.8 4861.3 73636.3 91323.0 91380.5 91543.0 91745.0 13033.9 13245.9 13275.0 13381.1 13542.6 25001.6 25014.9 25179.7 25283.0 25625.5 9564.3 9818.8 9867.3 9885.6 9972.5 4889.9 5157.1 5215.2 5233.4 5240.0 3820.8 4032.8 4044.4 4062.4 4063.6 659.7 667.7 670.7 671.5 672.8 599.4 687.4 734.2 746.4 749.3 10354.2 10367.5 10399.4 10791.9 10882.8 581.1 736.8 746.1 754.2 759.7 4840.3 5033.7 5035.2 5079.3 5081.4 75898.6 77584.2 111960.0 133533.3 134816.0 86818.1 113723.4 118678.4 119627.0 120154.0 57545.4 78573.0 90953.0 94407.5 96081.5 10400.4 10502.1 10508.9 10622.3 10790.2 53264.7 54238.7 54274.7 54658.7 55302.7 72189.0 81152.0 82329.2 82547.0 82628.0 13788.8 14516.6 14693.0 14711.5 14823.9 14545.4 14685.6 14857.6 14980.5 16133.7 11994.5 14417.6 15091.2 15105.6 15964.1 14561.6 15314.2 15374.8 15914.4 16713.2 14590.7 14636.2 15479.0 15770.1 16129.5 11789.6 12082.9 12278.8 12733.3 13315.3 12580.7 12820.2 12859.5 12882.7 12906.5 15:02:58 INFO - PROCESS | 24762 | -------- Summary: end -------- 15:02:58 INFO - PROCESS | 24762 | 15:02:59 INFO - TEST-INFO | 24762: exit 0 15:02:59 INFO - TEST-OK | dromaeo_css | took 1114613ms 15:02:59 INFO - TEST-START | kraken 15:02:59 INFO - operating with platform_type : linux_ 15:02:59 INFO - Initialising browser for kraken test... 15:02:59 INFO - TEST-INFO | started process 25082 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcSYPZe/profile http://localhost:45825/getInfo.html) 15:03:04 INFO - PROCESS | 25082 | __metrics Screen width/height:1600/1200 15:03:04 INFO - PROCESS | 25082 | colorDepth:24 15:03:04 INFO - PROCESS | 25082 | Browser inner width/height: 1024/697 15:03:04 INFO - PROCESS | 25082 | __metrics 15:03:04 INFO - PROCESS | 25082 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:03:04 INFO - PROCESS | 25082 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:03:05 INFO - PROCESS | 25082 | console.error: PushService: 15:03:05 INFO - PROCESS | 25082 | stateChangeProcessEnqueue: Error transitioning state 15:03:05 INFO - PROCESS | 25082 | UnknownError 15:03:06 INFO - TEST-INFO | 25082: exit 0 15:03:06 INFO - Browser initialized. 15:03:06 INFO - Running cycle 1/1 for kraken test... 15:03:06 INFO - TEST-INFO | started process 25173 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcSYPZe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:03:17 INFO - PROCESS | 25173 | RSS: Main: 147193856 15:03:17 INFO - PROCESS | 25173 | 15:03:20 INFO - PROCESS | 25173 | Cycle 1(1): loaded http://localhost:45825/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45825/tests/kraken/driver.html?testName=audio-beat-detection) 15:03:21 INFO - PROCESS | 25173 | RSS: Main: 188727296 15:03:21 INFO - PROCESS | 25173 | 15:03:23 INFO - PROCESS | 25173 | Cycle 1(1): loaded http://localhost:45825/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45825/tests/kraken/driver.html?testName=audio-dft) 15:03:24 INFO - PROCESS | 25173 | RSS: Main: 178155520 15:03:24 INFO - PROCESS | 25173 | 15:03:27 INFO - PROCESS | 25173 | Cycle 1(1): loaded http://localhost:45825/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45825/tests/kraken/driver.html?testName=audio-fft) 15:03:27 INFO - PROCESS | 25173 | RSS: Main: 268427264 15:03:27 INFO - PROCESS | 25173 | 15:03:29 INFO - PROCESS | 25173 | Cycle 1(1): loaded http://localhost:45825/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45825/tests/kraken/driver.html?testName=audio-oscillator) 15:03:30 INFO - PROCESS | 25173 | RSS: Main: 175882240 15:03:30 INFO - PROCESS | 25173 | 15:03:32 INFO - PROCESS | 25173 | Cycle 1(1): loaded http://localhost:45825/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45825/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:03:33 INFO - PROCESS | 25173 | RSS: Main: 177299456 15:03:33 INFO - PROCESS | 25173 | 15:03:35 INFO - PROCESS | 25173 | Cycle 1(1): loaded http://localhost:45825/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45825/tests/kraken/driver.html?testName=imaging-darkroom) 15:03:36 INFO - PROCESS | 25173 | RSS: Main: 213086208 15:03:36 INFO - PROCESS | 25173 | 15:03:39 INFO - PROCESS | 25173 | Cycle 1(1): loaded http://localhost:45825/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45825/tests/kraken/driver.html?testName=imaging-desaturate) 15:03:40 INFO - PROCESS | 25173 | RSS: Main: 211976192 15:03:40 INFO - PROCESS | 25173 | 15:03:43 INFO - PROCESS | 25173 | Cycle 1(1): loaded http://localhost:45825/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45825/tests/kraken/driver.html?testName=json-parse-financial) 15:03:43 INFO - PROCESS | 25173 | RSS: Main: 211927040 15:03:43 INFO - PROCESS | 25173 | 15:03:45 INFO - PROCESS | 25173 | Cycle 1(1): loaded http://localhost:45825/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45825/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:03:45 INFO - PROCESS | 25173 | RSS: Main: 173928448 15:03:45 INFO - PROCESS | 25173 | 15:03:47 INFO - PROCESS | 25173 | Cycle 1(1): loaded http://localhost:45825/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45825/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:03:48 INFO - PROCESS | 25173 | RSS: Main: 181968896 15:03:48 INFO - PROCESS | 25173 | 15:03:50 INFO - PROCESS | 25173 | Cycle 1(1): loaded http://localhost:45825/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45825/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:03:50 INFO - PROCESS | 25173 | RSS: Main: 183308288 15:03:50 INFO - PROCESS | 25173 | 15:03:53 INFO - PROCESS | 25173 | Cycle 1(1): loaded http://localhost:45825/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45825/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:03:53 INFO - PROCESS | 25173 | RSS: Main: 184324096 15:03:53 INFO - PROCESS | 25173 | 15:03:56 INFO - PROCESS | 25173 | Cycle 1(1): loaded http://localhost:45825/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45825/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:03:57 INFO - PROCESS | 25173 | RSS: Main: 182968320 15:03:57 INFO - PROCESS | 25173 | 15:03:58 INFO - PROCESS | 25173 | Cycle 1(1): loaded http://localhost:45825/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45825/tests/kraken/driver.html?testName=ai-astar) 15:03:59 INFO - PROCESS | 25173 | RSS: Main: 234147840 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | __start_tp_report 15:03:59 INFO - PROCESS | 25173 | _x_x_mozilla_page_load 15:03:59 INFO - PROCESS | 25173 | _x_x_mozilla_page_load_details 15:03:59 INFO - PROCESS | 25173 | |i|pagename|runs| 15:03:59 INFO - PROCESS | 25173 | |0;ai-astar;109;110;111;109;112;110;111;111;111;110 15:03:59 INFO - PROCESS | 25173 | |1;audio-beat-detection;140;117;118;154;117;117;116;116;155;120 15:03:59 INFO - PROCESS | 25173 | |2;audio-dft;160;160;161;159;161;162;160;160;163;160 15:03:59 INFO - PROCESS | 25173 | |3;audio-fft;76;75;75;112;76;75;75;75;96;75 15:03:59 INFO - PROCESS | 25173 | |4;audio-oscillator;82;81;109;81;82;81;118;81;80;118 15:03:59 INFO - PROCESS | 25173 | |5;imaging-gaussian-blur;116;116;117;117;117;117;117;117;117;116 15:03:59 INFO - PROCESS | 25173 | |6;imaging-darkroom;147;146;146;145;145;146;146;146;145;145 15:03:59 INFO - PROCESS | 25173 | |7;imaging-desaturate;94;96;95;95;94;95;95;95;95;95 15:03:59 INFO - PROCESS | 25173 | |8;json-parse-financial;49;49;49;49;48;49;50;48;50;50 15:03:59 INFO - PROCESS | 25173 | |9;json-stringify-tinderbox;59;60;59;59;61;58;60;59;60;60 15:03:59 INFO - PROCESS | 25173 | |10;stanford-crypto-aes;74;74;74;76;73;78;73;75;80;72 15:03:59 INFO - PROCESS | 25173 | |11;stanford-crypto-ccm;121;121;129;125;115;116;125;126;146;126 15:03:59 INFO - PROCESS | 25173 | |12;stanford-crypto-pbkdf2;152;151;156;157;150;150;154;154;152;151 15:03:59 INFO - PROCESS | 25173 | |13;stanford-crypto-sha256-iterative;63;72;62;62;64;62;64;62;61;63 15:03:59 INFO - PROCESS | 25173 | __end_tp_report 15:03:59 INFO - PROCESS | 25173 | __start_cc_report 15:03:59 INFO - PROCESS | 25173 | _x_x_mozilla_cycle_collect,1604 15:03:59 INFO - PROCESS | 25173 | __end_cc_report 15:03:59 INFO - PROCESS | 25173 | __startTimestamp1474409039037__endTimestamp 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | ------- Summary: start ------- 15:03:59 INFO - PROCESS | 25173 | Number of tests: 14 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:0.97 (0.9%) stddev-sans-first:0.88 15:03:59 INFO - PROCESS | 25173 | Values: 109.0 110.0 111.0 109.0 112.0 110.0 111.0 111.0 111.0 110.0 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | [#1] audio-beat-detection Cycles:10 Average:127.00 Median:119.00 stddev:16.17 (13.6%) stddev-sans-first:16.46 15:03:59 INFO - PROCESS | 25173 | Values: 140.0 117.0 118.0 154.0 117.0 117.0 116.0 116.0 155.0 120.0 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | [#2] audio-dft Cycles:10 Average:160.60 Median:160.50 stddev:1.17 (0.7%) stddev-sans-first:1.22 15:03:59 INFO - PROCESS | 25173 | Values: 160.0 160.0 161.0 159.0 161.0 162.0 160.0 160.0 163.0 160.0 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.00 stddev:12.70 (16.9%) stddev-sans-first:13.34 15:03:59 INFO - PROCESS | 25173 | Values: 76.0 75.0 75.0 112.0 76.0 75.0 75.0 75.0 96.0 75.0 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:81.00 stddev:16.55 (20.4%) stddev-sans-first:17.20 15:03:59 INFO - PROCESS | 25173 | Values: 82.0 81.0 109.0 81.0 82.0 81.0 118.0 81.0 80.0 118.0 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 15:03:59 INFO - PROCESS | 25173 | Values: 116.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.53 15:03:59 INFO - PROCESS | 25173 | Values: 147.0 146.0 146.0 145.0 145.0 146.0 146.0 146.0 145.0 145.0 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 15:03:59 INFO - PROCESS | 25173 | Values: 94.0 96.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | [#8] json-parse-financial Cycles:10 Average:49.10 Median:49.00 stddev:0.74 (1.5%) stddev-sans-first:0.78 15:03:59 INFO - PROCESS | 25173 | Values: 49.0 49.0 49.0 49.0 48.0 49.0 50.0 48.0 50.0 50.0 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.85 (1.4%) stddev-sans-first:0.88 15:03:59 INFO - PROCESS | 25173 | Values: 59.0 60.0 59.0 59.0 61.0 58.0 60.0 59.0 60.0 60.0 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:74.50 stddev:2.47 (3.3%) stddev-sans-first:2.60 15:03:59 INFO - PROCESS | 25173 | Values: 74.0 74.0 74.0 76.0 73.0 78.0 73.0 75.0 80.0 72.0 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | [#11] stanford-crypto-ccm Cycles:10 Average:125.00 Median:125.50 stddev:8.64 (6.9%) stddev-sans-first:9.04 15:03:59 INFO - PROCESS | 25173 | Values: 121.0 121.0 129.0 125.0 115.0 116.0 125.0 126.0 146.0 126.0 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.70 Median:153.00 stddev:2.45 (1.6%) stddev-sans-first:2.59 15:03:59 INFO - PROCESS | 25173 | Values: 152.0 151.0 156.0 157.0 150.0 150.0 154.0 154.0 152.0 151.0 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - PROCESS | 25173 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:63.00 stddev:3.14 (5.0%) stddev-sans-first:3.32 15:03:59 INFO - PROCESS | 25173 | Values: 63.0 72.0 62.0 62.0 64.0 62.0 64.0 62.0 61.0 63.0 15:03:59 INFO - PROCESS | 25173 | -------- Summary: end -------- 15:03:59 INFO - PROCESS | 25173 | 15:03:59 INFO - TEST-INFO | 25173: exit 0 15:04:00 INFO - TEST-OK | kraken | took 60363ms 15:04:00 INFO - SUITE-END | took 1174s 15:04:00 INFO - Completed test suite (00:19:35) 15:04:00 INFO - javascript benchmark 15:04:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3074.5234488654255, 3116.392303500817, 3134.2321131322824, 3151.5902932618756, 3166.3983843069827, 5593.888122237558, 5680.31836179658, 5682.147677848319, 5687.17535957286, 5701.287339082614, 5638.0, 5711.371749107595, 5715.0, 5720.11338242572, 5746.339170995327, 5135.666181698187, 5151.5106064923775, 5163.0, 5201.765920533585, 5226.5557427618505, 5027.974860125694, 5248.5538729208065, 5251.579873610102, 5257.13257312543, 5272.551833094191, 5018.673786203885, 5093.949060509403, 5114.130597798383, 5122.897542049157, 5138.023775482665, 5171.637985341028, 5195.688258704471, 5199.844004679866, 5256.08018596744, 5268.947310526923, 5105.259737338102, 5143.7685304161405, 5217.295665085241, 5221.973890130563, 5248.8425347239645, 5419.864503387407, 5454.236406903036, 5483.890322193533, 5504.2018907258625, 5510.1459273812625, 5900.527957763412, 5927.199828023205, 5948.107783832416, 6012.068129439944, 6022.337542870281, 6056.666883321415, 6057.545684073712, 6060.878782424371, 6069.028955367165, 6070.939290607126, 5413.621046526745, 5437.945620543823, 5527.088030474967, 5567.972160139255, 5592.720363981866, 6128.080787881764, 6196.504279657616, 6197.1324014637985, 6198.969005154945, 6199.194104766351, 6008.098785182257, 6019.759209631653, 6022.036474164201, 6027.939720602738, 6029.698515074228, 5851.38560451155, 5894.2926848778425, 5976.0, 6033.064874944391, 6040.637561746309, 6007.459328660441, 6013.639181649028, 6013.639181649115, 6018.037114061816, 6052.12244224585, 6019.067044608093, 6055.455009049207, 6061.090836374579, 6078.665673388006, 6083.270007599028, 4490.281554951192, 4495.303227999665, 4561.452625684873, 4601.217792975202, 4645.6980296280635, 4085.387191921176, 4109.321961876317, 4122.484689413824, 4124.6906482013965, 4145.0, 5749.597528172988, 5759.1937128802, 5760.279965004374, 5762.596618236683, 5769.480746732815, 5816.854578635568, 5872.677002764804, 5965.463108320272, 6073.848153796191, 6093.573449858467, 5675.4892059714, 5737.770489180386, 5739.0, 5762.884742305214, 5777.740001699934, 5224.738763061908, 5232.686038837682, 5234.031704134671, 5236.293100431393, 5237.450067742909, 6082.118092876508, 6130.417610327012, 6180.5784669526265, 6232.158658581034, 6239.96880015597, 5913.408659134224, 5924.644521328734, 5941.37615550361, 6028.914795336881, 6066.939330606638, 6175.752969881154, 6175.876482470415, 6187.81436556904, 6188.783392581238, 6191.9690401547705, 5796.94203057964, 5875.618084824473, 5876.147958545901, 5881.264841894763, 5909.940900590939, 5840.649561026352, 5981.671008094597, 5996.520278377807, 6013.0, 6132.540059495466, 5890.4698577128265, 5933.2880054393745, 5943.227380440688, 5946.0189068803165, 5947.494462970696, 5943.762249509971, 5945.137954996588, 5949.52403807703, 5955.821325360246, 5957.880842383041, 5597.63615365, 5712.0860662293835, 5757.769689212552, 5763.164341170591, 5813.447722466359, 5958.7616495341435, 5974.522038237017, 5979.1928089707335, 5980.671063091398, 6006.87986240264, 5604.131359639263, 5656.886862262814, 5803.767849285981, 5805.10020946754, 5813.476787089182], "value": 5547.087927348508, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.7239183779542, 626.9561130720848, 638.3488841381787, 649.0912722188934, 655.9930506672259, 34613.480797788055, 35368.46947295789, 38022.42966355507, 38051.238975220476, 38065.23869522608, 48127.278090828666, 49590.0, 49728.75135624326, 53803.46196538033, 53825.0, 9026.27789776818, 9156.633734650606, 9182.586783594721, 9192.908070919271, 9200.907990920106, 8255.215754503313, 9166.54167291636, 9264.861027084566, 9291.303152263574, 9311.95344023279, 8516.914830851674, 10980.945095274512, 11188.66434006981, 11230.606928757496, 11575.710607234845, 10969.23215374924, 11044.226904116675, 11058.170637202242, 11078.723031924186, 11134.053605443547, 11035.062019728333, 11092.389918554476, 11093.3898635575, 11108.16688748339, 11108.777824443547, 46858.5314146861, 47164.292535611996, 47403.28895066577, 47458.52541474575, 47656.76171619154, 3799.67702745267, 3830.751001184914, 3865.922681546381, 3869.8839034829, 3882.8835134946, 35586.46620300672, 35932.64067359345, 35968.0, 36087.458688119696, 37803.621963780286, 4446.177457170402, 4452.0, 4521.553103007039, 4544.182047231497, 4627.467841198271, 362448.0, 363045.0, 363946.0, 364416.0, 366130.0, 38979.0, 66821.6658916712, 67266.66366668136, 67424.0, 67448.66275668688, 40201.0, 42873.142537149084, 42882.35676464855, 42886.57113428826, 42926.57073429288, 17376.131193440277, 17479.737803932952, 17551.91224043897, 17564.209610567556, 17568.824311756718, 14498.565043048726, 14551.490697825526, 14635.560933172022, 14640.487582934546, 14649.267536623127, 9052.683156089637, 9223.44659320443, 9234.0, 9670.468124253233, 9674.854877176847, 3479.304139172176, 3574.1779390740276, 3686.318031164243, 3709.258148370283, 3735.9252814943547, 1021.4790456867051, 1060.9787804244027, 1075.860138182047, 1174.2191442690687, 1222.5782105173878, 35298.64701352954, 35510.0, 35514.28971420557, 35631.82184089063, 35749.64250357515, 1101.388729255255, 1191.755690083531, 1192.791261529229, 1228.7173949991552, 1266.3351740336213, 18578.163982620816, 18619.534511637048, 18666.533336666693, 18670.159842807105, 18904.338348157748, 569272.0, 573519.0, 576657.0, 577153.0, 578035.0, 55519.0, 56594.71702641543, 64845.67577162084, 65632.67183664051, 68297.3170268291, 1396.1832328087978, 1448.5943935698022, 1448.8478709735537, 1455.7961885336063, 1458.358322338168, 34234.828825856704, 36779.44830827593, 36923.261534768615, 37005.81497092497, 37152.0, 53073.734631326595, 53297.0, 53355.0, 53425.73287133539, 53520.73239633933, 454642.0, 542447.0, 553169.0, 553297.0, 893815.0, 48062.0, 48318.0, 49037.2644410327, 49309.0, 49674.75162624164, 52939.47060529499, 53142.0, 53315.73342133265, 53414.465855340946, 53464.0, 44111.0, 45298.54701453075, 45312.773436133924, 46495.30257046079, 47500.7624961873, 44890.326645099696, 45165.774171128935, 46388.536114638424, 48813.75593122012, 50007.74996124996, 44950.55049449464, 45651.771741141085, 45873.31190032219, 45976.540234597225, 46128.53871461243, 37669.62330376661, 39447.605523944396, 40350.59649403586, 40392.59607404006, 40721.38917916174, 29237.41525169527, 30285.731424458412, 30308.696913030588, 30525.389492210477, 30592.0], "value": 21157.92846691269, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.4820881063428, 436.37816112040343, 443.5010613060307, 445.43429844097994, 449.41351536245185, 18129.0, 20385.18459261628, 20407.285744998928, 21121.15515379383, 22256.55486890261, 20727.170913163493, 20750.377488675364, 20767.27314543991, 20875.686864697043, 20928.686069708965, 5623.26897503325, 6024.698765061729, 6058.303295121052, 6060.666663333515, 6069.57512974092, 5940.910886336708, 5975.0, 5978.671173085479, 6074.058520929263, 6074.969625151868, 5937.198478205431, 5989.700514974255, 5992.880142397149, 5992.940070599282, 5995.790147344844, 5786.045302525057, 5946.910796338058, 5975.940240597582, 5985.281766188064, 5991.670458124801, 5374.677519348852, 5397.757100930428, 5416.593755468316, 5419.701916394597, 5420.91868621971, 24390.39024024396, 24410.877945610337, 24775.752242477705, 24843.378915527075, 25032.749672503225, 2479.690038745157, 2503.7120731115974, 2525.10358822619, 2571.7813985811226, 2644.576867701178, 15022.173780442072, 15050.774238386324, 15077.396904123822, 15692.372305107781, 16019.279132439067, 1981.8711783734154, 2007.4479518132484, 2015.4256037029522, 2026.280670362015, 2154.4721543222013, 61562.38437615656, 66199.66900165516, 80619.5969020157, 82965.58517207435, 87508.5624571873, 10520.684379468628, 10768.56925722978, 10858.0, 11027.61403350879, 11562.135096087148, 16749.91625041867, 16751.413700520425, 16773.83226167747, 16778.496645100666, 16795.328186872634, 8666.959964804264, 8843.557822108998, 8962.193402593797, 8994.010658827523, 9005.909940900638, 8543.658253669784, 8658.610362533702, 8742.606582703793, 8792.648294068165, 8818.382713210014, 6903.206131294965, 6973.721051157999, 7123.216446190915, 7167.856642867114, 7191.964040179766, 2091.309867743641, 2099.5276062885728, 2101.8633788803677, 2121.4619400934507, 2123.61774880523, 978.5351957819978, 1007.0483393193508, 1013.2451323763843, 1023.0690072034413, 1111.9332840029624, 20658.380248592566, 21584.78415215859, 21662.675059874113, 21744.238951636933, 21809.56380872374, 1031.3914790273775, 1031.7936412717486, 1050.8633877595864, 1058.2539309786582, 1094.578587243917, 11030.448477576088, 12054.035677145806, 12157.452914618863, 12180.330081845583, 12309.076819238593, 433903.0, 441260.0, 445673.0, 451065.0, 453754.0, 81507.59246203731, 82500.58749706214, 83780.58109709412, 84493.57753211194, 84937.57531212427, 1083.7940791249796, 1134.5405110929921, 1155.2952698853692, 1155.3299086529792, 1176.3588844079998, 18399.632007360047, 18419.26322947067, 18843.15205815769, 18912.0932009547, 19009.904950475713, 42654.78672606617, 43069.92325191895, 43187.0, 43482.47811326588, 43902.12195756131, 56067.0, 56689.43310566842, 57367.139492906805, 58392.0, 58520.0, 8361.581920903809, 8614.65541378338, 8792.296616270554, 8870.379073464796, 8907.421017633833, 11583.884161158281, 12435.564755233523, 12706.872931270938, 12716.427760750603, 12723.618291451272, 8984.505852178192, 9078.31912606544, 9578.521073946136, 9975.900240997787, 11348.602798901999, 8889.955550222467, 9103.954480227556, 9302.906970930204, 9708.0, 9726.124648781644, 9310.581023854078, 9314.580843861906, 9447.401964459828, 9598.679336286992, 10140.391576505433, 13580.117292375964, 13809.378577963815, 13848.515301964382, 13985.720285594027, 14056.648583785487, 13364.799528007283, 13550.59348219555, 13703.0, 13742.0, 13790.172589644653], "value": 9746.59514082145, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1382.7096309774947, 1516.4313382481573, 1557.9844201557985, 1709.7093494106, 1821.5537193387622, 8142.796430089244, 8835.204831565166, 9111.407758495694, 9123.498207598588, 9454.0, 9186.448813071203, 9252.768680782983, 9270.67552635658, 9271.258299336054, 9290.442573445598, 2536.9365765855864, 2566.1916496303697, 2569.8843552040203, 2570.627259047437, 2570.897164113433, 2468.90124395024, 2528.203615861007, 2530.8987640494356, 2534.6071358939394, 2537.251510804308, 2500.9124680636182, 2527.317624241454, 2533.645289659449, 2534.303066656675, 2534.3157347516158, 2448.424620214257, 2473.183849329726, 2475.7152927413217, 2482.044412901037, 2489.103922587867, 2410.7348191698898, 2439.926802195937, 2451.705795304557, 2458.311672731638, 2460.815438842076, 9152.176304132594, 9213.30900182489, 9366.34435589509, 9401.858972115424, 9404.106609872051, 5582.944170558283, 5801.941980580183, 5806.82579522615, 5828.358880523139, 5836.503897168745, 3750.4374343848367, 3750.924981500382, 3830.9808450957844, 3833.17586718854, 3855.1904100139004, 1157.70632550394, 1184.8281999110081, 1187.145255416099, 1217.2878865863481, 1217.738186289943, 12707.618771436872, 12755.744885102245, 12766.170198937132, 12805.679858003532, 12879.098463107586, 4757.09615173116, 4785.49752276013, 4912.975435122801, 4935.876603084952, 4979.45226025137, 10260.127889129508, 10275.075243227995, 10285.074343308988, 10319.690409287734, 10336.689899303034, 5633.746481408347, 5651.717414129277, 5675.716214189274, 5683.062294721407, 5683.403242659544, 4464.15181115587, 4529.184746745552, 4582.908341833145, 4667.0, 5292.52367286946, 3970.4242884781543, 4051.3517837145914, 4174.519930208065, 4185.874423767292, 4186.392973018956, 1232.2544860359533, 1236.5857437758273, 1244.5519612939336, 1245.6574442028334, 1267.258653687603, 993.4387071304834, 1003.2826529031777, 1014.898510148994, 1016.7813920007234, 1030.0111892583204, 6130.846728831815, 6134.78528251509, 6144.354842741448, 6301.054841773771, 6555.541112122201, 479.4989236248129, 491.64847134299146, 492.7240745182709, 496.9204927211701, 540.8945255675184, 4347.608715215646, 4468.0, 4473.261911784519, 4482.843100491467, 4552.362669226311, 11259.774804503864, 11784.0, 11936.820947685792, 12042.09684273683, 12044.337561434206, 11877.58428455, 11924.0, 11945.522179112739, 11954.521819127138, 11975.101867359808, 7855.0, 8204.548749818818, 8205.097439281673, 8908.8663670045, 8997.325200609981, 6259.561830671809, 6373.904391434225, 6434.2922278548385, 6539.0, 6558.770443034471, 10434.37393756377, 10552.630657926935, 10615.8938410618, 10754.677359679223, 10863.67408977732, 10961.780764384826, 10995.945020274847, 11034.834477483004, 11058.723531911766, 11086.113110950942, 8431.536265505456, 8532.488050716976, 8663.523506207219, 8732.388732788899, 8738.169873861973, 8984.371094023356, 9091.681791137278, 9115.316351273548, 9211.447313161232, 9296.209822164921, 7878.4091193161885, 7899.407544434305, 7946.0, 8052.154523775153, 8161.632726527202, 7617.771466855782, 7698.538087714755, 7707.537547747153, 7877.251661092187, 8161.673533058611, 7232.0959880015, 7234.782956511102, 7268.236835132235, 7301.890471643036, 7312.817179570553, 5167.22491626259, 5268.236105764566, 5302.28419163408, 5347.625666203328, 5349.893002140013, 5313.335833020872, 5541.251930989265, 5564.721763911707, 5565.220869078251, 5629.971850140723], "value": 4813.361562908272, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.93557159711247, 364.3860095738681, 366.6260414377337, 368.32780176178466, 369.6432942210766, 5608.43915608439, 5642.492175704186, 5650.180723795047, 5667.931205110136, 5701.714914254281, 5227.634065615409, 5535.308086489189, 5538.47384498472, 5541.861453463655, 5602.635828671134, 3848.2111167210796, 4295.699301048928, 4379.277419225824, 4391.0559229765595, 4402.339649052652, 4382.312809568307, 4385.671074669397, 4388.4201687392515, 4388.451443569554, 4401.427814384126, 4145.523264824538, 4375.387445757597, 4382.583654552828, 4384.649228061748, 4393.406890069848, 4206.516250631171, 4268.765217913014, 4364.214441400547, 4365.3451982202605, 4375.343698445226, 3893.552241492236, 3896.3181443247317, 3905.3165696003084, 3905.7265991380614, 3910.5502867170353, 5228.843134705927, 5411.377691565481, 5432.0, 5434.429384914567, 5435.673859568438, 4921.8523444296725, 4953.356063711694, 4975.875603109951, 4983.377077865267, 5015.14742493777, 5824.679642619654, 5828.601135727437, 5829.795957141479, 5839.328477225131, 5853.795117170921, 3973.006748312922, 4239.47006624172, 4260.893477663083, 4265.786710664454, 4272.978635106804, 5763.711814409305, 5892.410758924073, 6004.42957918997, 6076.574639775217, 6096.4817990470865, 4195.328747400462, 4338.394793926267, 4347.869563913087, 4351.10802285537, 4353.760543170093, 4386.517483076859, 4397.846075387346, 4404.273294906368, 4413.44831896013, 4416.80124394403, 4902.0, 5017.89964200714, 5036.143855544567, 5044.142495775727, 5051.368578927634, 5874.267091208104, 5885.882282354329, 5889.9692553802915, 5900.822975310747, 5918.615290006136, 1489.9925500372647, 1514.1672080355982, 1518.2484670088377, 1529.7093552225042, 1530.1737061986403, 921.861720741894, 969.0842154164247, 982.9803403931882, 989.3922376138775, 989.5101924547395, 5018.1647369237, 5040.949590504048, 5043.773030213649, 5058.873528161752, 5059.797608095709, 5274.4198138204765, 5290.841274761763, 5298.337707786527, 5298.523132918056, 5310.097283461821, 4832.51674832521, 4907.975460122677, 4988.6757360771435, 5028.84913452597, 5073.543381095646, 2829.929251768722, 2832.7535884210884, 2846.84342361168, 2859.542473204319, 2863.642044744407, 5502.669839809624, 5518.751656175482, 5521.420250873681, 5523.28197334344, 5525.944740552623, 5379.520631826201, 5706.514946229534, 6173.197484327098, 6215.192025036717, 6231.532635052389, 6224.502039836802, 6238.875222495525, 6253.343398943228, 6266.308096813835, 6279.183706118267, 5879.441453061952, 6086.756529738938, 6088.178095956989, 6096.634201947897, 6116.3272040076445, 5937.287525496968, 5953.047512397996, 6013.639181649115, 6042.214512113397, 6045.123457098608, 5919.348871624204, 6022.849428764316, 6034.818955431344, 6109.602875813058, 6223.96888015557, 4253.829846806093, 4380.561943805594, 4397.428334316518, 4401.757903315349, 4403.471583410012, 5875.0, 6039.15451836752, 6044.214252147192, 6044.335123136363, 6063.333033366238, 5812.505936995402, 5866.41335866427, 5893.705314734332, 5896.3514013457625, 5902.1441890926435, 5820.272465941757, 5834.432849546186, 5867.442592953663, 5874.383189765184, 5875.882482350415, 5681.801136960186, 5783.768649253982, 5818.50542703858, 5827.679477628772, 5831.79588714393, 5880.353161152356, 5886.117082437669, 5898.675572843535, 5905.72232411016, 5922.703864806828, 5456.399796022513, 5736.971315143398, 5751.396103409082, 5754.251947246831, 5759.0], "value": 4403.710012508069, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.3762183820186, 807.0396228488095, 826.330672155554, 827.5407149032286, 904.9140331668493, 16740.0, 17703.645927081452, 17720.556986075353, 17737.02446365451, 17763.733543996834, 15599.84400155998, 16741.0, 17340.132993350344, 17367.218475168615, 17369.652606947853, 723.1611330856208, 745.4483682075279, 849.1932663969224, 850.0649285785648, 876.3077169036453, 860.4020205956863, 879.7668617816252, 880.4453194487496, 880.6653471680785, 880.770999540121, 929.6792606550763, 930.014184963937, 930.1117432851644, 930.1953809954374, 930.5440334236209, 759.9772006839776, 783.1502819440914, 883.8099808541194, 924.2098006204699, 924.6440120553601, 669.4109183918121, 673.6766352150995, 673.7473447457204, 673.8281738156787, 673.8787018336698, 17440.0, 17521.211545480484, 17554.21006054718, 17561.648767024715, 17563.385281515086, 15862.682746345008, 15944.202789860461, 15954.28205730745, 16147.515574532783, 16251.593710157222, 14543.127412355187, 14625.634359141, 14680.853191468163, 14842.851571484363, 14923.925380373137, 4795.976020119877, 4801.591864691505, 4817.686850354716, 4860.781264843091, 4861.270809378586, 73636.26363736401, 91323.0, 91380.54309728475, 91543.0, 91745.0, 13033.869661303266, 13245.933770331087, 13275.0, 13381.063325567116, 13542.59372218835, 25001.624975625382, 25014.874925625256, 25179.748202518105, 25283.0, 25625.487490250092, 9564.330496865292, 9818.80362392748, 9867.259955503367, 9885.555150018266, 9972.501374931224, 4889.926651100236, 5157.07172708916, 5215.243789650555, 5233.371995360581, 5240.0, 3820.84716611338, 4032.818523166406, 4044.373122166069, 4062.370332598452, 4063.6139566741113, 659.7328082126746, 667.6861874918781, 670.7350596514447, 671.5030877150873, 672.8284287506655, 599.4185639929261, 687.4225650453643, 734.1593875013081, 746.3767753925424, 749.3443237167478, 10354.223433242538, 10367.533460994273, 10399.376037437627, 10791.946040269748, 10882.755725267152, 581.1152445899178, 736.8137298948795, 746.1344839985699, 754.2306847016013, 759.6809340077181, 4840.273958906192, 5033.723145227048, 5035.219540971156, 5079.339685840817, 5081.390233172043, 75898.62050689712, 77584.2241577577, 111960.0, 133533.33233333772, 134816.0, 86818.13181868226, 113723.43138284255, 118678.4066079664, 119627.0, 120154.0, 57545.424545754846, 78573.0, 90952.99999999868, 94407.52796235975, 96081.51959240298, 10400.427976461368, 10502.119529524127, 10508.894911050791, 10622.256442048982, 10790.244682872124, 53264.73367633137, 54238.72880635571, 54274.72862635661, 54658.7267063678, 55302.72348638231, 72189.0, 81152.0, 82329.17670823455, 82547.0, 82628.0, 13788.793168102286, 14516.637084072983, 14693.0, 14711.485098021518, 14823.925880370529, 14545.418183272974, 14685.55943321702, 14857.628559286104, 14980.475683351031, 16133.677326453171, 11994.520219191134, 14417.567472975827, 15091.245437728288, 15105.622359441102, 15964.12197329158, 14561.635959101108, 15314.157721324988, 15374.846251537341, 15914.442994495137, 16713.247903844112, 14590.708185836436, 14636.195009274592, 15479.0, 15770.132642704302, 16129.516114516584, 11789.646310610695, 12082.879171208175, 12278.81581776292, 12733.29966851795, 13315.334233288491, 12580.685482862635, 12820.207552036798, 12859.549915752903, 12882.67793305175, 12906.483740650645], "value": 8275.776813723944, "unit": "score"}], "name": "dromaeo_css", "value": 7651.5931774869405, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 110.0, 111.0, 109.0, 112.0, 110.0, 111.0, 111.0, 111.0, 110.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 117.0, 118.0, 154.0, 117.0, 117.0, 116.0, 116.0, 155.0, 120.0], "value": 127.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 159.0, 161.0, 162.0, 160.0, 160.0, 163.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 112.0, 76.0, 75.0, 75.0, 75.0, 96.0, 75.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 109.0, 81.0, 82.0, 81.0, 118.0, 81.0, 80.0, 118.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 49.0, 49.0, 48.0, 49.0, 50.0, 48.0, 50.0, 50.0], "value": 49.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 61.0, 58.0, 60.0, 59.0, 60.0, 60.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 76.0, 73.0, 78.0, 73.0, 75.0, 80.0, 72.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 121.0, 129.0, 125.0, 115.0, 116.0, 125.0, 126.0, 146.0, 126.0], "value": 125.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 156.0, 157.0, 150.0, 150.0, 154.0, 154.0, 152.0, 151.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 72.0, 62.0, 62.0, 64.0, 62.0, 64.0, 62.0, 61.0, 63.0], "value": 63.5, "unit": "score"}], "name": "kraken", "value": 1452.3000000000002, "alertThreshold": 2.0}]} 15:04:00 INFO - Return code: 0 15:04:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:04:00 INFO - # TBPL SUCCESS # 15:04:00 INFO - Running post-action listener: _resource_record_post_action 15:04:00 INFO - [mozharness: 2016-09-20 22:04:00.333814Z] Finished run-tests step (success) 15:04:00 INFO - Running post-run listener: _resource_record_post_run 15:04:00 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 365735936; Read time: 0; Write time: 720668 15:04:00 INFO - TinderboxPrint: CPU usage
13.0% 15:04:00 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:04:00 INFO - TinderboxPrint: I/O write bytes / time
365,735,936 / 720,668 15:04:00 INFO - TinderboxPrint: CPU idle
8,267.2 (87.0%) 15:04:00 INFO - TinderboxPrint: CPU user
1,185.5 (12.5%) 15:04:00 INFO - TinderboxPrint: Swap in / out
0 / 0 15:04:00 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 119156736; Read time: 0; Write time: 306492 15:04:00 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 246239232; Read time: 0; Write time: 414176 15:04:00 INFO - Running post-run listener: _upload_blobber_files 15:04:00 INFO - Blob upload gear active. 15:04:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:04:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:04:00 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'] 15:04:00 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 15:04:01 INFO - (blobuploader) - INFO - Open directory for files ... 15:04:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:04:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:04:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:04:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:04:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:04:02 INFO - (blobuploader) - INFO - Done attempting. 15:04:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:04:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:04:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:04:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:04:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:04:06 INFO - (blobuploader) - INFO - Done attempting. 15:04:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:04:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:04:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:04:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:04:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:04:07 INFO - (blobuploader) - INFO - Done attempting. 15:04:07 INFO - (blobuploader) - INFO - Iteration through files over. 15:04:07 INFO - Return code: 0 15:04:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:04:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:04:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/71425d7f9f6af822d9ad99c6636fa85a9b671ad7d3c32538e57c56fee73da3b1cfa5df965bd0a3eef62e3843a2fe56cad3abd032ec4d11f53e4bf9b40ff77948", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6cebeff95206a82ddea9959cb260f0c5e8c5959a2359399fdfba85217beed0fd2ac30876a0a47743915f12085537ab825f99ec541cb5c2d513f71221abd3f39a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68d836eb6f95905b19f10fbab56554c7be56fc9bfc9f038054b9c0b7948b0818069d5b96abb6394b5678ad206942f8fb48ff1c6f892ce175d28922218125d472"} 15:04:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:04:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:04:07 INFO - Contents: 15:04:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/71425d7f9f6af822d9ad99c6636fa85a9b671ad7d3c32538e57c56fee73da3b1cfa5df965bd0a3eef62e3843a2fe56cad3abd032ec4d11f53e4bf9b40ff77948", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6cebeff95206a82ddea9959cb260f0c5e8c5959a2359399fdfba85217beed0fd2ac30876a0a47743915f12085537ab825f99ec541cb5c2d513f71221abd3f39a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68d836eb6f95905b19f10fbab56554c7be56fc9bfc9f038054b9c0b7948b0818069d5b96abb6394b5678ad206942f8fb48ff1c6f892ce175d28922218125d472"} 15:04:07 INFO - Running post-run listener: copy_logs_to_upload_dir 15:04:07 INFO - Copying logs to upload dir... 15:04:07 INFO - mkdir: /builds/slave/test/build/upload/logs 15:04:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.958355 ========= master_lag: 21.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-09-20 15:04:28.624652) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 15:04:28.628513) ========= 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=8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308 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/71425d7f9f6af822d9ad99c6636fa85a9b671ad7d3c32538e57c56fee73da3b1cfa5df965bd0a3eef62e3843a2fe56cad3abd032ec4d11f53e4bf9b40ff77948", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6cebeff95206a82ddea9959cb260f0c5e8c5959a2359399fdfba85217beed0fd2ac30876a0a47743915f12085537ab825f99ec541cb5c2d513f71221abd3f39a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68d836eb6f95905b19f10fbab56554c7be56fc9bfc9f038054b9c0b7948b0818069d5b96abb6394b5678ad206942f8fb48ff1c6f892ce175d28922218125d472"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011270 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/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/71425d7f9f6af822d9ad99c6636fa85a9b671ad7d3c32538e57c56fee73da3b1cfa5df965bd0a3eef62e3843a2fe56cad3abd032ec4d11f53e4bf9b40ff77948", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6cebeff95206a82ddea9959cb260f0c5e8c5959a2359399fdfba85217beed0fd2ac30876a0a47743915f12085537ab825f99ec541cb5c2d513f71221abd3f39a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68d836eb6f95905b19f10fbab56554c7be56fc9bfc9f038054b9c0b7948b0818069d5b96abb6394b5678ad206942f8fb48ff1c6f892ce175d28922218125d472"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 15:04:28.666020) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 15:04:28.666411) ========= 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=8783db0840cc980e80bb0bd400000236-1474407808.575740-788953308 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004494 ========= master_lag: 0.24 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 15:04:28.912584) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 15:04:28.912960) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 15:04:28.913403) ========= ========= Total master_lag: 21.60 =========