builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-027 starttime: 1481734112.82 results: success (0) buildid: 20161214083713 builduid: 072a9271ec3b4c26b70a43ba9b36919c revision: 80b23ce5f23f3d1a45618a92d7e369e26964b651 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 08:48:32.818327) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 08:48:32.818849) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 08:48:32.819154) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 08:48:32.834489) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:48:32.835056) ========= 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=1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-14 08:48:33-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2016-12-14 08:48:33 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111323 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:48:32.960844) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:48:32.961502) ========= 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=1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016621 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:48:33.012641) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:48:33.013019) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 80b23ce5f23f3d1a45618a92d7e369e26964b651 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 80b23ce5f23f3d1a45618a92d7e369e26964b651 --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=1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-14 08:48:33,359 truncating revision to first 12 chars 2016-12-14 08:48:33,359 Setting DEBUG logging. 2016-12-14 08:48:33,359 attempt 1/10 2016-12-14 08:48:33,359 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/80b23ce5f23f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-14 08:48:33,674 unpacking tar archive at: mozilla-inbound-80b23ce5f23f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.626176 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:48:33.653062) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 08:48:33.653441) ========= script_repo_revision: 80b23ce5f23f3d1a45618a92d7e369e26964b651 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 08:48:33.653880) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 08:48:33.654210) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 08:48:33.663418) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-14 08:48:33.663844) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:48:34 INFO - MultiFileLogger online at 20161214 08:48:34 in /builds/slave/test 08:48:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 08:48:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:48:34 INFO - {'append_to_log': False, 08:48:34 INFO - 'base_work_dir': '/builds/slave/test', 08:48:34 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 08:48:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:48:34 INFO - 'branch': 'Mozilla-Inbound', 08:48:34 INFO - 'buildbot_json_path': 'buildprops.json', 08:48:34 INFO - 'config_files': ('talos/linux_config.py',), 08:48:34 INFO - 'default_actions': ('clobber', 08:48:34 INFO - 'read-buildbot-config', 08:48:34 INFO - 'download-and-extract', 08:48:34 INFO - 'populate-webroot', 08:48:34 INFO - 'create-virtualenv', 08:48:34 INFO - 'install', 08:48:34 INFO - 'run-tests'), 08:48:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:48:34 INFO - 'download_minidump_stackwalk': True, 08:48:34 INFO - 'download_symbols': 'ondemand', 08:48:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:48:34 INFO - 'tooltool.py': '/tools/tooltool.py', 08:48:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:48:34 INFO - '/tools/misc-python/virtualenv.py')}, 08:48:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:48:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:48:34 INFO - 'installer_path': 'installer.exe', 08:48:34 INFO - 'log_level': 'info', 08:48:34 INFO - 'log_name': 'talos', 08:48:34 INFO - 'log_to_console': True, 08:48:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:48:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:48:34 INFO - 'opt_config_files': (), 08:48:34 INFO - 'pip_index': False, 08:48:34 INFO - 'sps_profile': False, 08:48:34 INFO - 'sps_profile_interval': 0, 08:48:34 INFO - 'suite': 'dromaeojs', 08:48:34 INFO - 'system_bits': '32', 08:48:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:48:34 INFO - 'title': 'talos-linux64-ix-027', 08:48:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:48:34 INFO - 'use_talos_json': True, 08:48:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:48:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:48:34 INFO - 'work_dir': 'build'} 08:48:34 INFO - [mozharness: 2016-12-14 16:48:34.113961Z] Running clobber step. 08:48:34 INFO - Running pre-action listener: _resource_record_pre_action 08:48:34 INFO - Running main action method: clobber 08:48:34 INFO - rmtree: /builds/slave/test/build 08:48:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:48:35 INFO - Running post-action listener: _resource_record_post_action 08:48:35 INFO - [mozharness: 2016-12-14 16:48:35.216775Z] Finished clobber step (success) 08:48:35 INFO - [mozharness: 2016-12-14 16:48:35.216875Z] Running read-buildbot-config step. 08:48:35 INFO - Running pre-action listener: _resource_record_pre_action 08:48:35 INFO - Running main action method: read_buildbot_config 08:48:35 INFO - Using buildbot properties: 08:48:35 INFO - { 08:48:35 INFO - "project": "", 08:48:35 INFO - "product": "firefox", 08:48:35 INFO - "script_repo_revision": "production", 08:48:35 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 08:48:35 INFO - "repository": "", 08:48:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 08:48:35 INFO - "buildid": "20161214083713", 08:48:35 INFO - "stage_platform": "linux64-pgo", 08:48:35 INFO - "basedir": "/builds/slave/test", 08:48:35 INFO - "buildnumber": 2258, 08:48:35 INFO - "platform": "ubuntu64_hw", 08:48:35 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:48:35 INFO - "slavebuilddir": "test", 08:48:35 INFO - "branch": "mozilla-inbound", 08:48:35 INFO - "revision": "80b23ce5f23f3d1a45618a92d7e369e26964b651", 08:48:35 INFO - "slavename": "talos-linux64-ix-027", 08:48:35 INFO - "builduid": "072a9271ec3b4c26b70a43ba9b36919c", 08:48:35 INFO - "repo_path": "integration/mozilla-inbound" 08:48:35 INFO - } 08:48:35 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 08:48:35 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 08:48:35 INFO - Running post-action listener: _resource_record_post_action 08:48:35 INFO - [mozharness: 2016-12-14 16:48:35.219236Z] Finished read-buildbot-config step (success) 08:48:35 INFO - [mozharness: 2016-12-14 16:48:35.219324Z] Running download-and-extract step. 08:48:35 INFO - Running pre-action listener: _resource_record_pre_action 08:48:35 INFO - Running main action method: download_and_extract 08:48:35 INFO - mkdir: /builds/slave/test/build/tests 08:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:35 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:48:35 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:48:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:48:35 INFO - Downloaded 1361 bytes. 08:48:35 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:48:35 INFO - Using the following test package requirements: 08:48:35 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 08:48:35 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:35 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:48:35 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:35 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:48:35 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:35 INFO - u'jsshell-linux-x86_64.zip'], 08:48:35 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:35 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:48:35 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 08:48:35 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:35 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:48:35 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:35 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:48:35 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:35 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:48:35 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:35 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:48:35 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:48:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 08:48:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:48:35 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:48:35 INFO - Expected file size: 21775271 08:48:35 INFO - Obtained file size: 21775271 08:48:39 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:48:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 08:48:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:48:39 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:48:39 INFO - Expected file size: 21775271 08:48:39 INFO - Obtained file size: 21775271 08:48:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 08:48:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:48:44 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 08:48:44 INFO - Expected file size: 14409734 08:48:44 INFO - Obtained file size: 14409734 08:48:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:44 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:48:44 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:48:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:48:45 INFO - Downloaded 59913733 bytes. 08:48:45 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:48:45 INFO - mkdir: /builds/slave/test/properties 08:48:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:48:45 INFO - Writing to file /builds/slave/test/properties/build_url 08:48:45 INFO - Contents: 08:48:45 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:48:45 INFO - Running post-action listener: _resource_record_post_action 08:48:45 INFO - Running post-action listener: set_extra_try_arguments 08:48:45 INFO - [mozharness: 2016-12-14 16:48:45.598301Z] Finished download-and-extract step (success) 08:48:45 INFO - [mozharness: 2016-12-14 16:48:45.598529Z] Running populate-webroot step. 08:48:45 INFO - Running pre-action listener: _resource_record_pre_action 08:48:45 INFO - Running main action method: populate_webroot 08:48:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:48:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:48:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:48:45 INFO - '--tppagecycles', 08:48:45 INFO - '7'], 08:48:45 INFO - 'tests': ['tsvgm']}}, 08:48:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:48:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:48:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:48:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:48:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:48:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:48:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:48:45 INFO - 'talos_options': ['--disable-e10s'], 08:48:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:48:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:48:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:48:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:48:45 INFO - 'talos_options': ['--disable-e10s'], 08:48:45 INFO - 'tests': ['damp', 'tps']}, 08:48:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:48:45 INFO - 'tests': ['damp', 'tps']}, 08:48:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:48:45 INFO - 'tests': ['dromaeo_dom']}, 08:48:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:48:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:48:45 INFO - 'tests': ['basic_compositor_video']}, 08:48:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:48:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:48:45 INFO - 'tests': ['a11yr', 08:48:45 INFO - 'ts_paint', 08:48:45 INFO - 'tpaint', 08:48:45 INFO - 'sessionrestore', 08:48:45 INFO - 'sessionrestore_no_auto_restore', 08:48:45 INFO - 'tabpaint']}, 08:48:45 INFO - 'other-e10s': {'tests': ['a11yr', 08:48:45 INFO - 'ts_paint', 08:48:45 INFO - 'tpaint', 08:48:45 INFO - 'sessionrestore', 08:48:45 INFO - 'sessionrestore_no_auto_restore', 08:48:45 INFO - 'tabpaint']}, 08:48:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:48:45 INFO - 'ts_paint', 08:48:45 INFO - 'tpaint', 08:48:45 INFO - 'sessionrestore', 08:48:45 INFO - 'sessionrestore_no_auto_restore', 08:48:45 INFO - 'tabpaint']}, 08:48:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:48:45 INFO - 'ts_paint', 08:48:45 INFO - 'tpaint', 08:48:45 INFO - 'sessionrestore', 08:48:45 INFO - 'sessionrestore_no_auto_restore', 08:48:45 INFO - 'tabpaint']}, 08:48:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:48:45 INFO - 'tests': ['a11yr', 08:48:45 INFO - 'ts_paint', 08:48:45 INFO - 'tpaint', 08:48:45 INFO - 'sessionrestore', 08:48:45 INFO - 'sessionrestore_no_auto_restore', 08:48:45 INFO - 'tabpaint']}, 08:48:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:48:45 INFO - 'tests': ['a11yr', 08:48:45 INFO - 'ts_paint', 08:48:45 INFO - 'tpaint', 08:48:45 INFO - 'sessionrestore', 08:48:45 INFO - 'sessionrestore_no_auto_restore', 08:48:45 INFO - 'tabpaint']}, 08:48:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:48:45 INFO - 'tests': ['tsvgx', 08:48:45 INFO - 'tsvgr_opacity', 08:48:45 INFO - 'tart', 08:48:45 INFO - 'tscrollx', 08:48:45 INFO - 'cart', 08:48:45 INFO - 'tsvg_static']}, 08:48:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:48:45 INFO - 'tsvgr_opacity', 08:48:45 INFO - 'tart', 08:48:45 INFO - 'tscrollx', 08:48:45 INFO - 'cart', 08:48:45 INFO - 'tsvg_static']}, 08:48:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:48:45 INFO - 'talos_options': ['--disable-e10s'], 08:48:45 INFO - 'tests': ['tp5o']}, 08:48:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:48:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:48:45 INFO - 'talos_options': ['--disable-e10s', 08:48:45 INFO - '--xperf_path', 08:48:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:48:45 INFO - 'tests': ['tp5n']}, 08:48:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:48:45 INFO - 'talos_options': ['--xperf_path', 08:48:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:48:45 INFO - 'tests': ['tp5n']}}, 08:48:45 INFO - 'talos.zip': {'path': '', 08:48:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:48:45 INFO - Running post-action listener: _resource_record_post_action 08:48:45 INFO - [mozharness: 2016-12-14 16:48:45.618605Z] Finished populate-webroot step (success) 08:48:45 INFO - [mozharness: 2016-12-14 16:48:45.618693Z] Running create-virtualenv step. 08:48:45 INFO - Running pre-action listener: _resource_record_pre_action 08:48:45 INFO - Running main action method: create_virtualenv 08:48:45 INFO - Creating virtualenv /builds/slave/test/build/venv 08:48:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:48:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:48:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:48:45 INFO - Using real prefix '/usr' 08:48:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:48:46 INFO - Installing distribute.............................................................................................................................................................................................done. 08:48:48 INFO - Installing pip.................done. 08:48:48 INFO - Return code: 0 08:48:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:48:48 INFO - Reading from file tmpfile_stdout 08:48:48 INFO - Output received: 08:48:48 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:48:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:48:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:48 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 0x7f40f736de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cdf20>, '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 0x193ac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193af90>, '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': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:48:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:48:48 INFO - Using env: {'DISPLAY': ':0', 08:48:48 INFO - 'HOME': '/home/cltbld', 08:48:48 INFO - 'LANG': 'en_US.UTF-8', 08:48:48 INFO - 'LANGUAGE': 'en_US:en', 08:48:48 INFO - 'LOGNAME': 'cltbld', 08:48:48 INFO - 'MAIL': '/var/mail/cltbld', 08:48:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:48 INFO - 'MOZ_NO_REMOTE': '1', 08:48:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:48 INFO - 'NO_EM_RESTART': '1', 08:48:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:48 INFO - 'PWD': '/builds/slave/test', 08:48:48 INFO - 'SHELL': '/bin/bash', 08:48:48 INFO - 'SHLVL': '1', 08:48:48 INFO - 'TERM': 'linux', 08:48:48 INFO - 'TMOUT': '86400', 08:48:48 INFO - 'USER': 'cltbld', 08:48:48 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 08:48:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:48 INFO - '_': '/tools/buildbot/bin/python'} 08:48:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:48:48 INFO - Cleaning up... 08:48:48 INFO - Return code: 0 08:48:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:48:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:48 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 0x7f40f736de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cdf20>, '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 0x193ac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193af90>, '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': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:48:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:48:48 INFO - Using env: {'DISPLAY': ':0', 08:48:48 INFO - 'HOME': '/home/cltbld', 08:48:48 INFO - 'LANG': 'en_US.UTF-8', 08:48:48 INFO - 'LANGUAGE': 'en_US:en', 08:48:48 INFO - 'LOGNAME': 'cltbld', 08:48:48 INFO - 'MAIL': '/var/mail/cltbld', 08:48:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:48 INFO - 'MOZ_NO_REMOTE': '1', 08:48:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:48 INFO - 'NO_EM_RESTART': '1', 08:48:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:48 INFO - 'PWD': '/builds/slave/test', 08:48:48 INFO - 'SHELL': '/bin/bash', 08:48:48 INFO - 'SHLVL': '1', 08:48:48 INFO - 'TERM': 'linux', 08:48:48 INFO - 'TMOUT': '86400', 08:48:48 INFO - 'USER': 'cltbld', 08:48:48 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 08:48:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:48 INFO - '_': '/tools/buildbot/bin/python'} 08:48:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:48 INFO - Downloading/unpacking psutil>=3.1.1 08:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:48:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:48:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:48:50 INFO - Installing collected packages: psutil 08:48:50 INFO - Running setup.py install for psutil 08:48:50 INFO - building 'psutil._psutil_linux' extension 08:48:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:48: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 08:48:51 INFO - building 'psutil._psutil_posix' extension 08:48: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 08:48: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_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:48:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:48:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:48:51 INFO - Successfully installed psutil 08:48:51 INFO - Cleaning up... 08:48:51 INFO - Return code: 0 08:48:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:51 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 0x7f40f736de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cdf20>, '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 0x193ac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193af90>, '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': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:48:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:48:51 INFO - Using env: {'DISPLAY': ':0', 08:48:51 INFO - 'HOME': '/home/cltbld', 08:48:51 INFO - 'LANG': 'en_US.UTF-8', 08:48:51 INFO - 'LANGUAGE': 'en_US:en', 08:48:51 INFO - 'LOGNAME': 'cltbld', 08:48:51 INFO - 'MAIL': '/var/mail/cltbld', 08:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:51 INFO - 'MOZ_NO_REMOTE': '1', 08:48:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:51 INFO - 'NO_EM_RESTART': '1', 08:48:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:51 INFO - 'PWD': '/builds/slave/test', 08:48:51 INFO - 'SHELL': '/bin/bash', 08:48:51 INFO - 'SHLVL': '1', 08:48:51 INFO - 'TERM': 'linux', 08:48:51 INFO - 'TMOUT': '86400', 08:48:51 INFO - 'USER': 'cltbld', 08:48:51 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 08:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:51 INFO - '_': '/tools/buildbot/bin/python'} 08:48:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:48:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:48:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:48:53 INFO - Installing collected packages: mozsystemmonitor 08:48:53 INFO - Running setup.py install for mozsystemmonitor 08:48:53 INFO - Successfully installed mozsystemmonitor 08:48:53 INFO - Cleaning up... 08:48:53 INFO - Return code: 0 08:48:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:48:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:53 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 0x7f40f736de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cdf20>, '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 0x193ac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193af90>, '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': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:48:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:48:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:48:53 INFO - Using env: {'DISPLAY': ':0', 08:48:53 INFO - 'HOME': '/home/cltbld', 08:48:53 INFO - 'LANG': 'en_US.UTF-8', 08:48:53 INFO - 'LANGUAGE': 'en_US:en', 08:48:53 INFO - 'LOGNAME': 'cltbld', 08:48:53 INFO - 'MAIL': '/var/mail/cltbld', 08:48:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:53 INFO - 'MOZ_NO_REMOTE': '1', 08:48:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:53 INFO - 'NO_EM_RESTART': '1', 08:48:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:53 INFO - 'PWD': '/builds/slave/test', 08:48:53 INFO - 'SHELL': '/bin/bash', 08:48:53 INFO - 'SHLVL': '1', 08:48:53 INFO - 'TERM': 'linux', 08:48:53 INFO - 'TMOUT': '86400', 08:48:53 INFO - 'USER': 'cltbld', 08:48:53 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 08:48:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:53 INFO - '_': '/tools/buildbot/bin/python'} 08:48:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:54 INFO - Downloading/unpacking jsonschema==2.5.1 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:48:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48:56 INFO - Downloading functools32-3.2.3-2.tar.gz 08:48:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:48:56 INFO - warning: no files found matching '*.txt' 08:48:56 INFO - no previously-included directories found matching 'build' 08:48:56 INFO - no previously-included directories found matching 'dist' 08:48:56 INFO - no previously-included directories found matching '.git*' 08:48:56 INFO - Installing collected packages: jsonschema, functools32 08:48:56 INFO - Running setup.py install for functools32 08:48:56 INFO - warning: no files found matching '*.txt' 08:48:56 INFO - no previously-included directories found matching 'build' 08:48:56 INFO - no previously-included directories found matching 'dist' 08:48:56 INFO - no previously-included directories found matching '.git*' 08:48:56 INFO - Successfully installed jsonschema functools32 08:48:56 INFO - Cleaning up... 08:48:56 INFO - Return code: 0 08:48:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:56 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 0x7f40f736de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cdf20>, '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 0x193ac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193af90>, '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': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:48:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:48:56 INFO - Using env: {'DISPLAY': ':0', 08:48:56 INFO - 'HOME': '/home/cltbld', 08:48:56 INFO - 'LANG': 'en_US.UTF-8', 08:48:56 INFO - 'LANGUAGE': 'en_US:en', 08:48:56 INFO - 'LOGNAME': 'cltbld', 08:48:56 INFO - 'MAIL': '/var/mail/cltbld', 08:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:56 INFO - 'MOZ_NO_REMOTE': '1', 08:48:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:56 INFO - 'NO_EM_RESTART': '1', 08:48:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:56 INFO - 'PWD': '/builds/slave/test', 08:48:56 INFO - 'SHELL': '/bin/bash', 08:48:56 INFO - 'SHLVL': '1', 08:48:56 INFO - 'TERM': 'linux', 08:48:56 INFO - 'TMOUT': '86400', 08:48:56 INFO - 'USER': 'cltbld', 08:48:56 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 08:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:56 INFO - '_': '/tools/buildbot/bin/python'} 08:48:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:48:56 INFO - Cleaning up... 08:48:56 INFO - Return code: 0 08:48:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:56 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 0x7f40f736de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cdf20>, '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 0x193ac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193af90>, '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': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:48:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:48:56 INFO - Using env: {'DISPLAY': ':0', 08:48:56 INFO - 'HOME': '/home/cltbld', 08:48:56 INFO - 'LANG': 'en_US.UTF-8', 08:48:56 INFO - 'LANGUAGE': 'en_US:en', 08:48:56 INFO - 'LOGNAME': 'cltbld', 08:48:56 INFO - 'MAIL': '/var/mail/cltbld', 08:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:56 INFO - 'MOZ_NO_REMOTE': '1', 08:48:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:56 INFO - 'NO_EM_RESTART': '1', 08:48:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:56 INFO - 'PWD': '/builds/slave/test', 08:48:56 INFO - 'SHELL': '/bin/bash', 08:48:56 INFO - 'SHLVL': '1', 08:48:56 INFO - 'TERM': 'linux', 08:48:56 INFO - 'TMOUT': '86400', 08:48:56 INFO - 'USER': 'cltbld', 08:48:56 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 08:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:56 INFO - '_': '/tools/buildbot/bin/python'} 08:48:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:56 INFO - Downloading/unpacking blobuploader==1.2.4 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48:58 INFO - Downloading blobuploader-1.2.4.tar.gz 08:48:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:48:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:48:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48:59 INFO - Downloading docopt-0.6.1.tar.gz 08:48:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:48:59 INFO - Installing collected packages: blobuploader, requests, docopt 08:48:59 INFO - Running setup.py install for blobuploader 08:48:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:48:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:48:59 INFO - Running setup.py install for requests 08:49:00 INFO - Running setup.py install for docopt 08:49:00 INFO - Successfully installed blobuploader requests docopt 08:49:00 INFO - Cleaning up... 08:49:00 INFO - Return code: 0 08:49:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:00 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 0x7f40f736de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cdf20>, '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 0x193ac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193af90>, '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': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:49:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:49:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:49:00 INFO - Using env: {'DISPLAY': ':0', 08:49:00 INFO - 'HOME': '/home/cltbld', 08:49:00 INFO - 'LANG': 'en_US.UTF-8', 08:49:00 INFO - 'LANGUAGE': 'en_US:en', 08:49:00 INFO - 'LOGNAME': 'cltbld', 08:49:00 INFO - 'MAIL': '/var/mail/cltbld', 08:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:00 INFO - 'MOZ_NO_REMOTE': '1', 08:49:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:00 INFO - 'NO_EM_RESTART': '1', 08:49:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:00 INFO - 'PWD': '/builds/slave/test', 08:49:00 INFO - 'SHELL': '/bin/bash', 08:49:00 INFO - 'SHLVL': '1', 08:49:00 INFO - 'TERM': 'linux', 08:49:00 INFO - 'TMOUT': '86400', 08:49:00 INFO - 'USER': 'cltbld', 08:49:00 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 08:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:00 INFO - '_': '/tools/buildbot/bin/python'} 08:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:00 INFO - Running setup.py (path:/tmp/pip-UEevJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:00 INFO - Running setup.py (path:/tmp/pip-8PLfNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:00 INFO - Running setup.py (path:/tmp/pip-4O5OEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:00 INFO - Running setup.py (path:/tmp/pip-noUpKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:00 INFO - Running setup.py (path:/tmp/pip-CgBKH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:00 INFO - Running setup.py (path:/tmp/pip-4C9RFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:01 INFO - Running setup.py (path:/tmp/pip-GNhFl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:01 INFO - Running setup.py (path:/tmp/pip-_ZjlVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:01 INFO - Running setup.py (path:/tmp/pip-B_hP7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:01 INFO - Running setup.py (path:/tmp/pip-joft1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:01 INFO - Running setup.py (path:/tmp/pip-qOVWDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:01 INFO - Running setup.py (path:/tmp/pip-nICg8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:01 INFO - Running setup.py (path:/tmp/pip-nq_Ia4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:01 INFO - Running setup.py (path:/tmp/pip-MeVeYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:01 INFO - Running setup.py (path:/tmp/pip-pQsgza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:01 INFO - Running setup.py (path:/tmp/pip-QrSaaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:01 INFO - Running setup.py (path:/tmp/pip-Wuitgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:49:01 INFO - Running setup.py install for manifestparser 08:49:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:49:01 INFO - Running setup.py install for mozcrash 08:49:02 INFO - Running setup.py install for mozdebug 08:49:02 INFO - Running setup.py install for mozdevice 08:49:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:49:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:49:02 INFO - Running setup.py install for mozfile 08:49:02 INFO - Running setup.py install for mozhttpd 08:49:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:49:02 INFO - Running setup.py install for mozinfo 08:49:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:49:02 INFO - Running setup.py install for mozInstall 08:49:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:49:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:49:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:49:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:49:02 INFO - Running setup.py install for mozleak 08:49:02 INFO - Running setup.py install for mozlog 08:49:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:49:02 INFO - Running setup.py install for moznetwork 08:49:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:49:02 INFO - Running setup.py install for mozprocess 08:49:03 INFO - Running setup.py install for mozprofile 08:49:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:49:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:49:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:49:03 INFO - Running setup.py install for mozrunner 08:49:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:49:03 INFO - Running setup.py install for mozscreenshot 08:49:03 INFO - Running setup.py install for moztest 08:49:03 INFO - Running setup.py install for mozversion 08:49:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:49:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:49:03 INFO - Cleaning up... 08:49:03 INFO - Return code: 0 08:49:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:03 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 0x7f40f736de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cdf20>, '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 0x193ac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193af90>, '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': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:49:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:49:03 INFO - Using env: {'DISPLAY': ':0', 08:49:03 INFO - 'HOME': '/home/cltbld', 08:49:03 INFO - 'LANG': 'en_US.UTF-8', 08:49:03 INFO - 'LANGUAGE': 'en_US:en', 08:49:03 INFO - 'LOGNAME': 'cltbld', 08:49:03 INFO - 'MAIL': '/var/mail/cltbld', 08:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:03 INFO - 'MOZ_NO_REMOTE': '1', 08:49:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:03 INFO - 'NO_EM_RESTART': '1', 08:49:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:03 INFO - 'PWD': '/builds/slave/test', 08:49:03 INFO - 'SHELL': '/bin/bash', 08:49:03 INFO - 'SHLVL': '1', 08:49:03 INFO - 'TERM': 'linux', 08:49:03 INFO - 'TMOUT': '86400', 08:49:03 INFO - 'USER': 'cltbld', 08:49:03 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 08:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:03 INFO - '_': '/tools/buildbot/bin/python'} 08:49:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:03 INFO - Running setup.py (path:/tmp/pip-Yqrlot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:03 INFO - Running setup.py (path:/tmp/pip-HFXZEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:04 INFO - Running setup.py (path:/tmp/pip-hiWQTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49: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)) 08:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:04 INFO - Running setup.py (path:/tmp/pip-mzq8gk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:04 INFO - Running setup.py (path:/tmp/pip-B9Rrup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49: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)) 08:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:04 INFO - Running setup.py (path:/tmp/pip-AHEXAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49: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)) 08:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:04 INFO - Running setup.py (path:/tmp/pip-N5_hmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49: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)) 08:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:04 INFO - Running setup.py (path:/tmp/pip-jvWEzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:04 INFO - Running setup.py (path:/tmp/pip-M94oHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:04 INFO - Running setup.py (path:/tmp/pip-vw5OVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:04 INFO - Running setup.py (path:/tmp/pip-wy03FG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:04 INFO - Running setup.py (path:/tmp/pip-70QNh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:04 INFO - Running setup.py (path:/tmp/pip-OqchPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:04 INFO - Running setup.py (path:/tmp/pip-CXdKBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:04 INFO - Running setup.py (path:/tmp/pip-ZKAu4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49: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)) 08:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:05 INFO - Running setup.py (path:/tmp/pip-p7iNTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49: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)) 08:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:05 INFO - Running setup.py (path:/tmp/pip-PuEfZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49: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)) 08:49: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==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:49: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==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:49: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)) 08:49: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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:49: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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:49:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:07 INFO - Downloading blessings-1.6.tar.gz 08:49:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:49:07 INFO - Installing collected packages: blessings 08:49:07 INFO - Running setup.py install for blessings 08:49:07 INFO - Successfully installed blessings 08:49:07 INFO - Cleaning up... 08:49:07 INFO - Return code: 0 08:49:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:49:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:07 INFO - Reading from file tmpfile_stdout 08:49:07 INFO - Current package versions: 08:49:07 INFO - argparse == 1.2.1 08:49:07 INFO - blessings == 1.6 08:49:07 INFO - blobuploader == 1.2.4 08:49:07 INFO - docopt == 0.6.1 08:49:07 INFO - functools32 == 3.2.3-2 08:49:07 INFO - jsonschema == 2.5.1 08:49:07 INFO - manifestparser == 1.1 08:49:07 INFO - mozInstall == 1.12 08:49:07 INFO - mozcrash == 1.0 08:49:07 INFO - mozdebug == 0.1 08:49:07 INFO - mozdevice == 0.49 08:49:07 INFO - mozfile == 1.2 08:49:07 INFO - mozhttpd == 0.7 08:49:07 INFO - mozinfo == 0.9 08:49:07 INFO - mozleak == 0.1 08:49:07 INFO - mozlog == 3.4 08:49:07 INFO - moznetwork == 0.27 08:49:07 INFO - mozprocess == 0.23 08:49:07 INFO - mozprofile == 0.28 08:49:07 INFO - mozrunner == 6.13 08:49:07 INFO - mozscreenshot == 0.1 08:49:07 INFO - mozsystemmonitor == 0.3 08:49:07 INFO - moztest == 0.7 08:49:07 INFO - mozversion == 1.4 08:49:07 INFO - psutil == 3.1.1 08:49:07 INFO - requests == 1.2.3 08:49:07 INFO - wsgiref == 0.1.2 08:49:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:07 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 0x7f40f736de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cdf20>, '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 0x193ac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193af90>, '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': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:49:07 INFO - Using env: {'DISPLAY': ':0', 08:49:07 INFO - 'HOME': '/home/cltbld', 08:49:07 INFO - 'LANG': 'en_US.UTF-8', 08:49:07 INFO - 'LANGUAGE': 'en_US:en', 08:49:07 INFO - 'LOGNAME': 'cltbld', 08:49:07 INFO - 'MAIL': '/var/mail/cltbld', 08:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:07 INFO - 'MOZ_NO_REMOTE': '1', 08:49:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:07 INFO - 'NO_EM_RESTART': '1', 08:49:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:07 INFO - 'PWD': '/builds/slave/test', 08:49:07 INFO - 'SHELL': '/bin/bash', 08:49:07 INFO - 'SHLVL': '1', 08:49:07 INFO - 'TERM': 'linux', 08:49:07 INFO - 'TMOUT': '86400', 08:49:07 INFO - 'USER': 'cltbld', 08:49:07 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 08:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:07 INFO - '_': '/tools/buildbot/bin/python'} 08:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:49:07 INFO - Cleaning up... 08:49:07 INFO - Return code: 0 08:49:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:07 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 0x7f40f736de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x193d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19cdf20>, '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 0x193ac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193af90>, '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': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:49:07 INFO - Using env: {'DISPLAY': ':0', 08:49:07 INFO - 'HOME': '/home/cltbld', 08:49:07 INFO - 'LANG': 'en_US.UTF-8', 08:49:07 INFO - 'LANGUAGE': 'en_US:en', 08:49:07 INFO - 'LOGNAME': 'cltbld', 08:49:07 INFO - 'MAIL': '/var/mail/cltbld', 08:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:07 INFO - 'MOZ_NO_REMOTE': '1', 08:49:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:07 INFO - 'NO_EM_RESTART': '1', 08:49:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:07 INFO - 'PWD': '/builds/slave/test', 08:49:07 INFO - 'SHELL': '/bin/bash', 08:49:07 INFO - 'SHLVL': '1', 08:49:07 INFO - 'TERM': 'linux', 08:49:07 INFO - 'TMOUT': '86400', 08:49:07 INFO - 'USER': 'cltbld', 08:49:07 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 08:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:07 INFO - '_': '/tools/buildbot/bin/python'} 08:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:49:08 INFO - Cleaning up... 08:49:08 INFO - Return code: 0 08:49:08 INFO - Running post-action listener: _resource_record_post_action 08:49:08 INFO - Running post-action listener: _start_resource_monitoring 08:49:08 INFO - Starting resource monitoring. 08:49:08 INFO - [mozharness: 2016-12-14 16:49:08.078620Z] Finished create-virtualenv step (success) 08:49:08 INFO - [mozharness: 2016-12-14 16:49:08.079091Z] Running install step. 08:49:08 INFO - Running pre-action listener: _resource_record_pre_action 08:49:08 INFO - Running main action method: install 08:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:08 INFO - Reading from file tmpfile_stdout 08:49:08 INFO - Detecting whether we're running mozinstall >=1.0... 08:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:49:08 INFO - Reading from file tmpfile_stdout 08:49:08 INFO - Output received: 08:49:08 INFO - Usage: mozinstall [options] installer 08:49:08 INFO - Options: 08:49:08 INFO - -h, --help show this help message and exit 08:49:08 INFO - -d DEST, --destination=DEST 08:49:08 INFO - Directory to install application into. [default: 08:49:08 INFO - "/builds/slave/test"] 08:49:08 INFO - --app=APP Application being installed. [default: firefox] 08:49:08 INFO - mkdir: /builds/slave/test/build/application 08:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:49:25 INFO - Reading from file tmpfile_stdout 08:49:25 INFO - Output received: 08:49:25 INFO - /builds/slave/test/build/application/firefox/firefox 08:49:25 INFO - Running post-action listener: _resource_record_post_action 08:49:25 INFO - [mozharness: 2016-12-14 16:49:25.373564Z] Finished install step (success) 08:49:25 INFO - [mozharness: 2016-12-14 16:49:25.373901Z] Running run-tests step. 08:49:25 INFO - Running pre-action listener: _resource_record_pre_action 08:49:25 INFO - Running main action method: run_tests 08:49:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:49:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:49:25 INFO - Python 2.7.3 08:49:25 INFO - Return code: 0 08:49:25 INFO - grabbing minidump binary from tooltool 08:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:25 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 0x19cdf20>, '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 0x193ac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193af90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:49:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:49:25 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:49:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:49:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:49:25 INFO - Return code: 0 08:49:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:49:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:49:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:49:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:49:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:49:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:49:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:49:25 INFO - Using env: {'DISPLAY': ':0', 08:49:25 INFO - 'HOME': '/home/cltbld', 08:49:25 INFO - 'LANG': 'en_US.UTF-8', 08:49:25 INFO - 'LANGUAGE': 'en_US:en', 08:49:25 INFO - 'LOGNAME': 'cltbld', 08:49:25 INFO - 'MAIL': '/var/mail/cltbld', 08:49:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:49:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:25 INFO - 'MOZ_NO_REMOTE': '1', 08:49:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:49:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:25 INFO - 'NO_EM_RESTART': '1', 08:49:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:25 INFO - 'PWD': '/builds/slave/test', 08:49:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:49:25 INFO - 'SHELL': '/bin/bash', 08:49:25 INFO - 'SHLVL': '1', 08:49:25 INFO - 'TERM': 'linux', 08:49:25 INFO - 'TMOUT': '86400', 08:49:25 INFO - 'USER': 'cltbld', 08:49:25 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369', 08:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:25 INFO - '_': '/tools/buildbot/bin/python'} 08:49:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:49:25 INFO - mozversion application_buildid: 20161214063614 08:49:25 INFO - mozversion application_changeset: 80b23ce5f23f3d1a45618a92d7e369e26964b651 08:49:25 INFO - mozversion application_display_name: Nightly 08:49:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:49:25 INFO - mozversion application_name: Firefox 08:49:25 INFO - mozversion application_remotingname: firefox 08:49:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 08:49:25 INFO - mozversion application_vendor: Mozilla 08:49:25 INFO - mozversion application_version: 53.0a1 08:49:25 INFO - mozversion platform_buildid: 20161214063614 08:49:25 INFO - mozversion platform_changeset: 80b23ce5f23f3d1a45618a92d7e369e26964b651 08:49:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 08:49:25 INFO - mozversion platform_version: 53.0a1 08:49:25 INFO - using testdate: 1481734165 08:49:25 INFO - actual date: 1481734165 08:49:25 INFO - starting webserver on 'localhost:49181' 08:49:25 INFO - SUITE-START | Running 2 tests 08:49:25 INFO - TEST-START | dromaeo_css 08:49:25 INFO - operating with platform_type : linux_ 08:49:25 INFO - Initialising browser for dromaeo_css test... 08:49:25 INFO - TEST-INFO | started process 30116 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps0BAOb/profile http://localhost:49181/getInfo.html) 08:49:29 INFO - PROCESS | 30116 | __metrics Screen width/height:1600/1200 08:49:29 INFO - PROCESS | 30116 | colorDepth:24 08:49:29 INFO - PROCESS | 30116 | Browser inner width/height: 1024/697 08:49:29 INFO - PROCESS | 30116 | __metrics 08:49:30 INFO - PROCESS | 30116 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:49:30 INFO - PROCESS | 30116 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:49:31 INFO - TEST-INFO | 30116: exit 0 08:49:31 INFO - Browser initialized. 08:49:31 INFO - Running cycle 1/1 for dromaeo_css test... 08:49:31 INFO - TEST-INFO | started process 30201 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps0BAOb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:49:43 INFO - PROCESS | 30201 | RSS: Main: 172142592 08:49:43 INFO - PROCESS | 30201 | 08:50:35 INFO - PROCESS | 30201 | 1481734235025 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:52:44 INFO - PROCESS | 30201 | Cycle 1(1): loaded http://localhost:49181/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49181/tests/dromaeo/cssquery-ext.html) 08:52:47 INFO - PROCESS | 30201 | RSS: Main: 458752000 08:52:47 INFO - PROCESS | 30201 | 08:55:50 INFO - PROCESS | 30201 | Cycle 1(1): loaded http://localhost:49181/tests/dromaeo/cssquery-ext.html (next: http://localhost:49181/tests/dromaeo/cssquery-jquery.html) 08:55:51 INFO - PROCESS | 30201 | RSS: Main: 294170624 08:55:51 INFO - PROCESS | 30201 | 08:58:53 INFO - PROCESS | 30201 | Cycle 1(1): loaded http://localhost:49181/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49181/tests/dromaeo/cssquery-mootools.html) 08:58:53 INFO - PROCESS | 30201 | RSS: Main: 226643968 08:58:53 INFO - PROCESS | 30201 | 09:01:56 INFO - PROCESS | 30201 | Cycle 1(1): loaded http://localhost:49181/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49181/tests/dromaeo/cssquery-prototype.html) 09:01:56 INFO - PROCESS | 30201 | RSS: Main: 233287680 09:01:56 INFO - PROCESS | 30201 | 09:04:59 INFO - PROCESS | 30201 | Cycle 1(1): loaded http://localhost:49181/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49181/tests/dromaeo/cssquery-yui.html) 09:05:00 INFO - PROCESS | 30201 | RSS: Main: 226377728 09:05:00 INFO - PROCESS | 30201 | 09:08:03 INFO - PROCESS | 30201 | Cycle 1(1): loaded http://localhost:49181/tests/dromaeo/cssquery-yui.html (next: http://localhost:49181/tests/dromaeo/cssquery-dojo.html) 09:08:03 INFO - PROCESS | 30201 | RSS: Main: 321110016 09:08:03 INFO - PROCESS | 30201 | 09:08:03 INFO - PROCESS | 30201 | __start_tp_report 09:08:03 INFO - PROCESS | 30201 | _x_x_mozilla_page_load 09:08:03 INFO - PROCESS | 30201 | _x_x_mozilla_page_load_details 09:08:03 INFO - PROCESS | 30201 | |i|pagename|runs| 09:08:03 INFO - PROCESS | 30201 | |0;dojo.html;3228.11226912599;3268.57508523892;3290.4406250937336;3298.983505082474;3302.4220761366755;5185.688858668483;5244.580433565315;5301.072312345344;5310.8141215057485;5322.973385133074;5279.392869819972;5318.228856815759;5414.729263536827;5417.133258678612;5439.238506609087;4815.374001379833;4818.253170758538;4848.53938875808;4873.025394921012;4884.438289596706;4881.658283920127;4951.826686065989;4994.950050499485;5003.899922001576;5045.470225626312;4933.7286449245275;4938.2839488274;4984.152694042022;5002.749862506878;5022.422421421528;4685.695429797052;4718.9292160617615;4721.57505824474;4769.475357710649;4774.021325628238;4731.952680473186;4739.099571081483;4742.857714268577;4744.762761861927;4751.382320298374;5093.567046801027;5147.356580427447;5149.588032957355;5149.613778966555;5149.897002059938;5666.886662266773;5685.658860468385;5716.514096301819;5731.684757338345;5802.854928626776;5751.511121554631;5758.510526605243;5773.624714393593;5784.652920824764;5790.536757059425;5183.896322073538;5343.919841202385;5391.973040134813;5397.54120899724;5398.24424580559;5627.324721033502;5653.830385088454;5699.316082070178;5733.684647344353;5839.591228613956;5553.055980483327;5557.722113894289;5560.165975103686;5560.666360018412;5565.860853478695;5616.719164041781;5633.352164501135;5640.943590564042;5644.604877658607;5644.604877658607;5493.615446918758;5534.1975413564805;5582.553395728413;5608.383077861432;5628.690422026746;5620.9437905621235;5625.156226566048;5627.915581266284;5641.746121424546;5673.801416950387;4355.4337936068105;4356.956430435718;4363.47638283408;4369.388285640013;4401.163778882003;3879.573246942834;3900.7659540427667;3909.237698648736;3954.8220330085214;3975.045988962628;5381.0583147948955;5430.755615997289;5434.809781657623;5477.808276710376;5482.451754824486;5682;5718.342390625048;5725.112607545837;5730.455606717355;5735.598508104393;5179.067767801832;5259.60552958522;5427.48438898304;5431.864203394868;5482.342118945672;4822.95177048225;4834.8791280218275;4842.612590992712;4843.15244832153;4844.660873738804;5740.598158128974;5743.483086522233;5743.597948143673;5746.195532625352;5746.195532625436;5545.473180047889;5547.694876781654;5554.611177217556;5557.833265002057;5557.972210138923;5753.1370294456165;5787.508061814793;5789.9710501447225;5800.56495762811;5805.158252053513;5571.387147413862;5579.944200557942;5583.050881350261;5585.1901474286815;5586.273784408001;5610.242617246783;5612.298462692163;5617.466340697627;5628.521575665951;5636.9718151410625;5502.944970550243;5549.944500555104;5554.861253442954;5563.13771365439;5568.49883510486;5508.779648814003;5512.118061110364;5517.944820551904;5524.91712624319;5602.159676048625;5234.842954711365;5257.0800109981365;5258.290130832289;5310.8141215057285;5314.468553144655;5430.619856609999;5469.15228139639;5473.808416705396;5473.8357849264585;5596.496315331639;5479.0411677957;5527.8894422112135;5531.612787104864;5557.3331200256225;5583.832485025456 09:08:03 INFO - PROCESS | 30201 | |1;ext.html;547.5920439272742;569.1491220625167;578.9607654260602;588.0150747497939;600.2586805295457;30605.234869128257;38407.42388864166;39896.40155397668;40008.79995600025;43357.7832110839;41506.16987660245;41744.37383439251;41784.791076044574;41796.58203417965;41888.16223675532;8544.999999999984;8725.301975841918;8726.781830454258;8732.781680457976;8735.650573977033;7619.466637335388;7819.2180781921925;9937.304388692794;10160.441175735332;10164.237682173829;10237.590496380173;10299.227557933147;10309.381437113798;10319.845202321934;10321.483925803717;10158.6952391428;10203.948980255125;10210.336328138721;10234.437105959169;10236.53935572901;10000.749981250454;10053.145482633985;10062.79874402515;10073.043060909202;10089.344192627455;39353.212935741125;39458.80270598629;39495.40756888649;39579.8021009896;39629.207415851524;3226.2095786532213;3256.1045712429272;3266.6406695263504;3288.57248557686;3341.7493687973256;29536.70463295361;29748.553771693227;30129.397412051854;31222.375552488822;31790.841045794856;4111.691623128278;4165.645920096796;4183.769892655902;4217.578242175789;4284.335927931175;313976;314315;315562.1325440939;315619;315677;53211.46788532065;53452;53605.463945360825;53621.73189134107;53647.6588085301;56577.43422565804;56730.716346418005;56872;56882.7155864218;56993.43006569964;14387.64030899215;14808.481703140338;14840.109593424213;14856.331465084097;14873;10639.414832184304;10900.40047797363;11752.823707644393;11767.940885320362;11814.05487560993;14616.707665846623;14822.62943426423;15052.397904083933;15394.615134621725;15534.533963981099;5503.9174412383845;5620.747066382022;5621.116925829811;5664.093745000862;5773.134029895549;849.9702510412229;899.3884158772098;910.2217603948528;970.1947383671536;1029.3206483720858;29935.850320748694;29980.128789350332;30083.117971642034;30110.849445752632;30653.846730766203;1005.9547320370601;1014.5333146752577;1071;1079.740862193068;1100.1034157162048;15104.773428398583;15748.527544173694;15903.363865445484;15986.60033499172;16010.359585616678;440953;445248;445418;445624;446233;54675.453245467834;54849.45150548444;54915.17627235515;54963.175552366745;55010.17484737732;1152.8097863852538;1216.4282787090053;1246.4079562207878;1297.0686249076966;1317.5520323090195;27456.862715686293;30745.692543075176;31512.212194695316;31624.680249629164;31676.683233168296;47057;47642.761786190844;47747.52252477431;47759.52240477551;47808.282875757584;283951;284914;286123;286589;286597;23763.881180593988;24395.634065488677;24458.87770561207;25854.095106671175;38286.80856595699;39145.41281880831;39427.21145577015;39838;40492.797536012135;40864;30597.23506912345;30825;31323.216919577193;31713.841430793622;32135.357292853543;33829;34395.656043439245;34531;34554.82722586371;35182.82408588043;33466;34197.145071373416;34364;34372;34942.65057349394;29845.70154298429;31228.53157202689;31633.20916977094;31712.682873170972;31717.841410792797;24002.39994000164;24311.784410779743;24434.63348049745;24616.753832461447;24837.751622483545 09:08:03 INFO - PROCESS | 30201 | |2;jquery.html;352.1636114228707;365.51021631014453;373.30006238303173;377.48661819924905;391.53211911765436;14017.088889222206;17173.313067477302;17406.999999999985;18043;18995.52511187221;17582.472525824247;17907.73138402922;18002;18044.17660681825;18213.90893045533;5218.582513398919;5403.081476149064;5432.91850622241;5439.5104440600335;5450.754716037788;3986.900327491807;4189;5446.509814116729;5560.6941618210985;5561.332640083196;4183.1215444756635;6050;6147.47746441553;6147.8770424591485;6152.0156774915795;5853.5902486825935;6014.51883849291;6138.938610613926;6141.17094192285;6146.446819786218;5304.92042619361;5334.973325133349;5335.866603334909;5348.304720386325;5350.678959262456;19342.226310947546;20124.496887577927;20287.087081081238;20644.96775161236;20899.89550052255;2132.17911104226;2148.323278167388;2221.311393468014;2482.8262021658493;2579.8323108997856;9257.814843703089;9295.72112836616;9336;9532.666356677482;9777.902220977841;1821.8633602479736;1848.1867978089597;1865.766779152606;1922.5001499610062;1980.485073880787;70760;76942;77151;77720;79620.20379796186;10110.140638045848;10366.274360794823;10478.161747060218;10639.308444951053;10712.196585256137;14465.132092074298;14526;14532.982691211513;14537.345819438151;14563.563093107225;7490;7763.835424686229;7786.961065194638;7937.2459616336355;7959.363250939911;7401.407887369105;7402.370798482081;7447.88828167578;7606.353459955855;7632.542047477169;4350.651947844165;4351.456067991501;4511.142882852248;4535.569120933506;4622.375979242826;1850.6113716119496;1853.3513270355268;1864.794872564017;1880.7084901840237;1926.6243082598758;905.8278927003848;912.8630705394244;949.0414681171928;981.5337714585514;1013.5641674080216;16558.917205414138;16768.41310554149;16907.746383804253;17929.641407172043;18189.54526136831;945.7257395355407;966.1160038564634;967.8693376394237;974.9853752193723;992.4591097851688;9465.384749991228;9923.305368624127;9970.102690757867;10001.399916005063;10002.449865257335;266884;270987;408426;411115;411832.0534357244;86904.5654771722;87713;87882;88561.11438885657;88744.5562772182;945.716285114463;972.9562169702522;983.1299300119302;990.980180396388;996.8106059848606;15747.842521574637;15776.447824326093;16324.755128672841;16518.25667844926;16784.07687577195;34465.82767086149;35914.4612830813;36393.63606363902;37531.812340939214;37605.81197093997;51113.23330150125;52165.47834521758;52300.939373184134;53297.46702532925;53869;7489.775306740807;7794.493357931717;7841.098273698598;7841.882371764314;7894.210578942289;8782.956085219534;11409.60066397672;12091.093168012258;12462.875371246171;12741.93629031886;10800.405977671304;11081.335119892832;11119.72200694989;11150.888491115309;11168.274062185932;10575.153987680813;10578.365298082139;10786.676399708022;10933.289336193122;11067.83398249043;10961.397123157984;11040.668779936615;11113.722156946142;11187.272827266203;11242.55029798799;6624.171978502687;6830.624315662686;6876.69054892521;7111.324424179695;7636.694532218649;9105;10329.638462653771;10466.842997355197;10612.734681633021;11308.717282068013 09:08:03 INFO - PROCESS | 30201 | |3;mootools.html;1323.497071112977;1397.8951578631602;1415.4691990503559;1544.582962600098;1744.607463320753;7385.593792341419;8113.91886081139;8377.664893404271;8400.49597024179;8626.266767324785;8417;8494.150584941503;8497.0653228145;8498.532580708059;8500.234978851902;1889.914953827081;1957.8042195780379;2151.3868547464017;2184.2682701295084;2185.1477923609805;2128.329576183505;2209.5969059647077;2209.884008575675;2216.5012872103807;2219.4784225706944;2181.738191417024;2181.967270490944;2189.255653077953;2193.166596693254;2231.464448532357;2142.2930432957164;2158.0396723458066;2177.8475506714535;2178.6078505868936;2178.8256939444805;2110.335244398025;2130.872147671145;2133.637281662121;2137.818285445739;2142.6893100500342;8293.751187464386;8461.111583283728;8461.323094152514;8471.788205294855;8474.915250847536;4951.826686066006;5089.694618322913;5100.234964755279;5105.770240339157;5110.514501122388;3179.3959147761852;3305.3719793239447;3423.6576342365815;3425.8972230833115;3434.553508043963;1057.8148823955853;1060.4008735064663;1060.973475663107;1073.7637719701731;1161.6340852631479;10945.507452164671;11269.492872820743;11293.548258069668;11694.532218711242;11720.179587428884;4080.2655522006326;4108.4864391951005;4222.78886055696;4231.767252801125;4269.786510674454;9141.588628511732;9142.405743626643;9145.314101442418;9152.359334846496;9153.496557689392;5049.141645920203;5087.949120508822;5089.389273287229;5090.312807770978;5090.949090509122;4125.608067233668;4150.709450338508;4589.35748995141;4755.952440475551;4756.857294281177;3730.515033045741;3751.6811071059265;3851.8266677998995;3876.8643097491454;3896.383001054587;1225.8099994500687;1243.8383010208795;1250.487300206929;1252.987470125287;1256.0830593666674;885.8759773631568;923.4297969943801;942.990570094304;949.2453499467939;970.4516947924541;5465.4261302563455;5650.943490565124;5754.798582049608;5918.171455996164;5927.8221653350465;902.3638334973828;932.2029664636638;936.3773090894479;937.4094320578129;942.119118624075;3761.39817629178;3792.867249646304;3850.2876967761053;3906.140649057203;4088.4889388826396;10208.897911020795;10306.227032972558;10376.584936602603;10386.584536618602;10398.532066057045;9704.660336888175;10321.84517232242;10324.174066074696;10329.74175645615;10333.844992325121;7484.32641062307;7637.465377423527;7667.693292268247;7668.386529077772;7679.961600192075;5292;5642.7460764266525;5649.180868773922;5682.721387687737;5998.550108741949;9444.574994125418;9494.572744226665;9522.23822094217;9547.52262386864;9622.566984485575;9423.151916327564;9426.62293508252;9589.712308630753;9605.903940960501;9615.9038409615;7271.091113610799;7484.476086674098;7497.212792656911;7533;7543.245675432413;7884.4086693497065;7971;8013.998250218722;8026.919730802617;8059.919400805917;7192.244814294633;7293.781186564412;7352.558846469229;7357.411407087528;7494.625268736432;7187.173475050227;7285.927140728525;7304.671289791866;7316.707331706672;7324.926750732425;6092.208012958287;6172.629642221481;6179.783707570214;6188.876222475616;6190.473809726223;4610.0779844031995;4613.884652883705;4665.976670116627;4666.766661666835;4669.5797378234765;4623.768811559476;4632.814687412466;4658.440987081572;4750.691205071659;4844.806207751651 09:08:03 INFO - PROCESS | 30201 | |4;prototype.html;364.215116424106;366.91744357519553;371.31863031337485;373.4305184593493;374.6216321515272;4979.302897594331;5071.594272458204;5080.542751152396;5148.948510514893;5157.329547158869;4908.748269191351;5037.546620804127;5057.797688092481;5061.291419201315;5093.6943783372935;4091.1975296921705;4096.774677392742;4100.56944020878;4103.917921641565;4104.240715467633;3774.981125094371;3820.789856557888;4084.2239974404915;4092.5088989321175;4097.549269580344;4007.9599204008023;4033.5159780826343;4049.3318602430563;4060.8984775380554;4066.7763273019973;4024.55729869717;4031.1937612477623;4037.515498140242;4062.146949140669;4067.3898915162667;3636.8363423646;3639.636036396366;3640.908977275563;3667.376545987162;3675.246574452231;4734.502877197879;4928.827491037797;4944.53026962438;4953.504649535053;4957.182064959314;4485.955140448619;4490.236659767815;4495.685302028859;4502.099580083964;4502.527234640348;5292.391374991848;5295.867603309909;5297.576193904479;5302.628815982883;5317.441668624816;3636.745427820027;3815.961840381616;3834.3098242316373;3851.768893866377;3855.9807200964096;5480.972595136999;5495.818399044185;5501.367342755594;5508.641938274;5510.283663123809;3934.2721596504166;3941.049180655594;3948.861789837342;3954.6638535724787;3957.861474848424;3910.393888947218;3919.392494163409;3921.2941670499586;4019.618136277049;4019.8995025124605;4495.056038232008;4501.582001669463;4504.77476126199;4529.230030894756;4532.076810457236;5302.416734159239;5306.946930530722;5321.840344789663;5321.882404695058;5327.920081198785;1264.7027948432112;1287.8454585449806;1301.9934900325627;1323.3184909771476;1339.70049052419;903.746950853762;923.1645360948353;923.2890674180843;925.6343744220995;927.5176908007799;5000.199968005175;5005.4744251853035;5024.834354356805;5034.672746271481;5042.571381432546;5240.9213861792105;5244.868878277997;5246.790128394898;5263.184206448007;5285.814996475182;5231.032259032091;5232.790688372498;5236.607254455931;5242.947570524322;5243.947560524422;2795.2313113893842;2796.860156992142;2817.3238422778386;2835.8582070896373;2840.6733225679313;5302.946970530323;5445.264889239981;5490.40778174333;5521.585881058937;5540.196671482612;5357.357117145967;5385.919211211835;5388.5419739321815;5401.540869026098;5408.296921400193;5422.53908417781;5460;5502.889942201214;5505.6696598204235;5530.225768392509;5167.715775632376;5261.763220655156;5396.676199428047;5428.918566221589;5438.592105592017;5408.242846001642;5408.72956352173;5408.917566736756;5412.296401467942;5431.23962645222;5383.300170977748;5628.746706398304;5641.8589535261945;5683.261176047087;5684.204211410323;3849.191669749384;3894.863679771308;3904.590018048064;3904.7657140571655;3910.8044597770568;5444.292242008513;5448.809291674773;5464.9453505464435;5466.508014278575;5469;5281.973590132178;5355.277037599874;5366.892662146814;5367.838964831061;5421.2681288025615;5297.231901374356;5303.708296043755;5307.601929855354;5315.468453154654;5357.1696387060065;4979.427365852828;5050.065737838511;5144.459831717616;5186.6369354144845;5239.633225674319;5293.385517417225;5295.729025034041;5333.573314134782;5338.265063854185;5355.892882142413;5085.313482679939;5199.896002080013;5213.879016011576;5232.607554433509;5241.816536421359 09:08:03 INFO - PROCESS | 30201 | |5;yui.html;690.7513295213722;749.8875168724694;755.7543798265561;785.7407055671629;810.6311628209163;12618.936905315471;13368.799468007976;13741.931290343548;13872.4451021959;14049.50826721065;11121.27711698739;12030.398480076004;12159.939200304008;12179.75640487192;12184.573539926105;767.1101522234195;779.5089093870854;782.0693374883898;782.1669921533577;782.5422128055095;775.9612019399036;778.1012930065783;781.6052893288881;784.2784638132903;788.1487992967582;825.983480330393;826.5454000299841;826.6156237349632;827.5283088639478;827.9586020698971;813.5037627047496;818.6111596991441;818.7339114787688;819.0335404223044;819.4304958054155;599.4994179859822;601.3865856826013;601.6390165900468;605.7274226598048;608.3126067543648;12177.208481448677;12197.512099516009;12204.511819527208;12347.135700500969;12442.751144977141;13975.510857120054;14609.78085328721;14654.926725366411;14687.26563671812;14703.63240918975;11486;11506.597269095626;11660.475278612483;11688.590899318484;11852.34812085335;3600.2979419013304;3633.927321453556;3636.436352365361;3685.963140368589;3810.237952409529;47792.5220747795;49010.50989490095;70164.29835701629;76678.23321766822;77610.22389776086;11085.944570277097;11108.777824443467;11154.040315687815;11363.090952723762;11625.76748465026;20739.481512961996;20753.896230518752;20763.79236207649;20779.168833246502;20784.896075519526;7851.803704907423;7932;7965.203479651988;8185.140560241208;8284.420090593601;4030.496187976503;4264.701470897069;4292.441982542249;4346.087321662486;4493.0115374617535;3471.392506311385;3504.369213541587;3541.38025845476;3555.715542756573;3563.198280386944;582.1820342418908;596.0463258785994;596.7314708381246;601.1703848688782;607.0742118269674;559.7956745787758;655.9639219842859;675.1864003875319;697.3549466743242;709.1029847243242;8938.50838203892;9237.30720195988;9255.676051338305;9438.811223775487;9736.95131524338;598.7844376024627;650.1840190560911;678.8438659108433;692.2316228985821;711.3633298198084;4084.1219137885496;4269.274223382033;4279.743215407086;4519.254323036662;4545.431821022372;74424.62787686136;77998.6100069496;78830.60584697155;79405.60297198592;81577;46826.06347873023;47473.76263118732;60082.69958650179;67872.66063669749;70345.29654703387;59315.40684593185;60373.396266036776;61355;62402;62844.28667849838;8941.239994600532;9131.360804743605;9284.396514226553;9486.715398538056;9511.239100871775;38525;39501.604983949794;40118;40128.79935600303;40645.39031914583;50494.24258636197;50956.49043509518;55460.72962334399;57630.7118464405;57930;11878.287302761863;11944.522219111139;12088.318120992973;12272.140950133406;12682.365881705693;12634.747305053663;12706.87293127057;12715.503236610006;12882;13032.218066916015;12061;12257.325847078497;12340.938295308466;12505.874941250471;12575.559855405383;13103.868961310645;13198.328191859962;13454.798178027533;13512.797308040585;13543.661408464866;12945.158564693265;12968.629114777312;13275.269860157785;13305.401256943655;13533.390997405337;10122.190224781853;10556.972683820737;10678.14144564237;10969.122470202525;11098.833517497082;10685.839712404164;10728.47417699779;11011;11015.669529914116;11019.559217631206 09:08:03 INFO - PROCESS | 30201 | __end_tp_report 09:08:03 INFO - PROCESS | 30201 | __start_cc_report 09:08:03 INFO - PROCESS | 30201 | _x_x_mozilla_cycle_collect,2899 09:08:03 INFO - PROCESS | 30201 | __end_cc_report 09:08:03 INFO - PROCESS | 30201 | __startTimestamp1481735283373__endTimestamp 09:08:03 INFO - PROCESS | 30201 | 09:08:03 INFO - PROCESS | 30201 | ------- Summary: start ------- 09:08:03 INFO - PROCESS | 30201 | Number of tests: 6 09:08:03 INFO - PROCESS | 30201 | 09:08:03 INFO - PROCESS | 30201 | [#0] dojo.html Cycles:165 Average:5260.64 Median:5479.04 stddev:556.85 (10.2%) stddev-sans-first:535.24 09:08:03 INFO - PROCESS | 30201 | Values: 3228.1 3268.6 3290.4 3299.0 3302.4 5185.7 5244.6 5301.1 5310.8 5323.0 5279.4 5318.2 5414.7 5417.1 5439.2 4815.4 4818.3 4848.5 4873.0 4884.4 4881.7 4951.8 4995.0 5003.9 5045.5 4933.7 4938.3 4984.2 5002.7 5022.4 4685.7 4718.9 4721.6 4769.5 4774.0 4732.0 4739.1 4742.9 4744.8 4751.4 5093.6 5147.4 5149.6 5149.6 5149.9 5666.9 5685.7 5716.5 5731.7 5802.9 5751.5 5758.5 5773.6 5784.7 5790.5 5183.9 5343.9 5392.0 5397.5 5398.2 5627.3 5653.8 5699.3 5733.7 5839.6 5553.1 5557.7 5560.2 5560.7 5565.9 5616.7 5633.4 5640.9 5644.6 5644.6 5493.6 5534.2 5582.6 5608.4 5628.7 5620.9 5625.2 5627.9 5641.7 5673.8 4355.4 4357.0 4363.5 4369.4 4401.2 3879.6 3900.8 3909.2 3954.8 3975.0 5381.1 5430.8 5434.8 5477.8 5482.5 5682.0 5718.3 5725.1 5730.5 5735.6 5179.1 5259.6 5427.5 5431.9 5482.3 4823.0 4834.9 4842.6 4843.2 4844.7 5740.6 5743.5 5743.6 5746.2 5746.2 5545.5 5547.7 5554.6 5557.8 5558.0 5753.1 5787.5 5790.0 5800.6 5805.2 5571.4 5579.9 5583.1 5585.2 5586.3 5610.2 5612.3 5617.5 5628.5 5637.0 5502.9 5549.9 5554.9 5563.1 5568.5 5508.8 5512.1 5517.9 5524.9 5602.2 5234.8 5257.1 5258.3 5310.8 5314.5 5430.6 5469.2 5473.8 5473.8 5596.5 5479.0 5527.9 5531.6 5557.3 5583.8 09:08:03 INFO - PROCESS | 30201 | 09:08:03 INFO - PROCESS | 30201 | [#1] ext.html Cycles:180 Average:50401.12 Median:31754.34 stddev:93763.78 (295.3%) stddev-sans-first:93952.10 09:08:03 INFO - PROCESS | 30201 | Values: 547.6 569.1 579.0 588.0 600.3 30605.2 38407.4 39896.4 40008.8 43357.8 41506.2 41744.4 41784.8 41796.6 41888.2 8545.0 8725.3 8726.8 8732.8 8735.7 7619.5 7819.2 9937.3 10160.4 10164.2 10237.6 10299.2 10309.4 10319.8 10321.5 10158.7 10203.9 10210.3 10234.4 10236.5 10000.7 10053.1 10062.8 10073.0 10089.3 39353.2 39458.8 39495.4 39579.8 39629.2 3226.2 3256.1 3266.6 3288.6 3341.7 29536.7 29748.6 30129.4 31222.4 31790.8 4111.7 4165.6 4183.8 4217.6 4284.3 313976.0 314315.0 315562.1 315619.0 315677.0 53211.5 53452.0 53605.5 53621.7 53647.7 56577.4 56730.7 56872.0 56882.7 56993.4 14387.6 14808.5 14840.1 14856.3 14873.0 10639.4 10900.4 11752.8 11767.9 11814.1 14616.7 14822.6 15052.4 15394.6 15534.5 5503.9 5620.7 5621.1 5664.1 5773.1 850.0 899.4 910.2 970.2 1029.3 29935.9 29980.1 30083.1 30110.8 30653.8 1006.0 1014.5 1071.0 1079.7 1100.1 15104.8 15748.5 15903.4 15986.6 16010.4 440953.0 445248.0 445418.0 445624.0 446233.0 54675.5 54849.5 54915.2 54963.2 55010.2 1152.8 1216.4 1246.4 1297.1 1317.6 27456.9 30745.7 31512.2 31624.7 31676.7 47057.0 47642.8 47747.5 47759.5 47808.3 283951.0 284914.0 286123.0 286589.0 286597.0 23763.9 24395.6 24458.9 25854.1 38286.8 39145.4 39427.2 39838.0 40492.8 40864.0 30597.2 30825.0 31323.2 31713.8 32135.4 33829.0 34395.7 34531.0 34554.8 35182.8 33466.0 34197.1 34364.0 34372.0 34942.7 29845.7 31228.5 31633.2 31712.7 31717.8 24002.4 24311.8 24434.6 24616.8 24837.8 09:08:03 INFO - PROCESS | 30201 | 09:08:03 INFO - PROCESS | 30201 | [#2] jquery.html Cycles:180 Average:24069.57 Median:411473.53 stddev:60250.19 (14.6%) stddev-sans-first:60392.89 09:08:03 INFO - PROCESS | 30201 | Values: 352.2 365.5 373.3 377.5 391.5 14017.1 17173.3 17407.0 18043.0 18995.5 17582.5 17907.7 18002.0 18044.2 18213.9 5218.6 5403.1 5432.9 5439.5 5450.8 3986.9 4189.0 5446.5 5560.7 5561.3 4183.1 6050.0 6147.5 6147.9 6152.0 5853.6 6014.5 6138.9 6141.2 6146.4 5304.9 5335.0 5335.9 5348.3 5350.7 19342.2 20124.5 20287.1 20645.0 20899.9 2132.2 2148.3 2221.3 2482.8 2579.8 9257.8 9295.7 9336.0 9532.7 9777.9 1821.9 1848.2 1865.8 1922.5 1980.5 70760.0 76942.0 77151.0 77720.0 79620.2 10110.1 10366.3 10478.2 10639.3 10712.2 14465.1 14526.0 14533.0 14537.3 14563.6 7490.0 7763.8 7787.0 7937.2 7959.4 7401.4 7402.4 7447.9 7606.4 7632.5 4350.7 4351.5 4511.1 4535.6 4622.4 1850.6 1853.4 1864.8 1880.7 1926.6 905.8 912.9 949.0 981.5 1013.6 16558.9 16768.4 16907.7 17929.6 18189.5 945.7 966.1 967.9 975.0 992.5 9465.4 9923.3 9970.1 10001.4 10002.4 266884.0 270987.0 408426.0 411115.0 411832.1 86904.6 87713.0 87882.0 88561.1 88744.6 945.7 973.0 983.1 991.0 996.8 15747.8 15776.4 16324.8 16518.3 16784.1 34465.8 35914.5 36393.6 37531.8 37605.8 51113.2 52165.5 52300.9 53297.5 53869.0 7489.8 7794.5 7841.1 7841.9 7894.2 8783.0 11409.6 12091.1 12462.9 12741.9 10800.4 11081.3 11119.7 11150.9 11168.3 10575.2 10578.4 10786.7 10933.3 11067.8 10961.4 11040.7 11113.7 11187.3 11242.6 6624.2 6830.6 6876.7 7111.3 7636.7 9105.0 10329.6 10466.8 10612.7 11308.7 09:08:03 INFO - PROCESS | 30201 | 09:08:03 INFO - PROCESS | 30201 | [#3] mootools.html Cycles:180 Average:5448.55 Median:5068.55 stddev:3076.48 (60.7%) stddev-sans-first:3069.49 09:08:03 INFO - PROCESS | 30201 | Values: 1323.5 1397.9 1415.5 1544.6 1744.6 7385.6 8113.9 8377.7 8400.5 8626.3 8417.0 8494.2 8497.1 8498.5 8500.2 1889.9 1957.8 2151.4 2184.3 2185.1 2128.3 2209.6 2209.9 2216.5 2219.5 2181.7 2182.0 2189.3 2193.2 2231.5 2142.3 2158.0 2177.8 2178.6 2178.8 2110.3 2130.9 2133.6 2137.8 2142.7 8293.8 8461.1 8461.3 8471.8 8474.9 4951.8 5089.7 5100.2 5105.8 5110.5 3179.4 3305.4 3423.7 3425.9 3434.6 1057.8 1060.4 1061.0 1073.8 1161.6 10945.5 11269.5 11293.5 11694.5 11720.2 4080.3 4108.5 4222.8 4231.8 4269.8 9141.6 9142.4 9145.3 9152.4 9153.5 5049.1 5087.9 5089.4 5090.3 5090.9 4125.6 4150.7 4589.4 4756.0 4756.9 3730.5 3751.7 3851.8 3876.9 3896.4 1225.8 1243.8 1250.5 1253.0 1256.1 885.9 923.4 943.0 949.2 970.5 5465.4 5650.9 5754.8 5918.2 5927.8 902.4 932.2 936.4 937.4 942.1 3761.4 3792.9 3850.3 3906.1 4088.5 10208.9 10306.2 10376.6 10386.6 10398.5 9704.7 10321.8 10324.2 10329.7 10333.8 7484.3 7637.5 7667.7 7668.4 7680.0 5292.0 5642.7 5649.2 5682.7 5998.6 9444.6 9494.6 9522.2 9547.5 9622.6 9423.2 9426.6 9589.7 9605.9 9615.9 7271.1 7484.5 7497.2 7533.0 7543.2 7884.4 7971.0 8014.0 8026.9 8059.9 7192.2 7293.8 7352.6 7357.4 7494.6 7187.2 7285.9 7304.7 7316.7 7324.9 6092.2 6172.6 6179.8 6188.9 6190.5 4610.1 4613.9 4666.0 4666.8 4669.6 4623.8 4632.8 4658.4 4750.7 4844.8 09:08:03 INFO - PROCESS | 30201 | 09:08:03 INFO - PROCESS | 30201 | [#4] prototype.html Cycles:180 Average:4470.74 Median:5119.08 stddev:1292.51 (25.2%) stddev-sans-first:1258.85 09:08:03 INFO - PROCESS | 30201 | Values: 364.2 366.9 371.3 373.4 374.6 4979.3 5071.6 5080.5 5148.9 5157.3 4908.7 5037.5 5057.8 5061.3 5093.7 4091.2 4096.8 4100.6 4103.9 4104.2 3775.0 3820.8 4084.2 4092.5 4097.5 4008.0 4033.5 4049.3 4060.9 4066.8 4024.6 4031.2 4037.5 4062.1 4067.4 3636.8 3639.6 3640.9 3667.4 3675.2 4734.5 4928.8 4944.5 4953.5 4957.2 4486.0 4490.2 4495.7 4502.1 4502.5 5292.4 5295.9 5297.6 5302.6 5317.4 3636.7 3816.0 3834.3 3851.8 3856.0 5481.0 5495.8 5501.4 5508.6 5510.3 3934.3 3941.0 3948.9 3954.7 3957.9 3910.4 3919.4 3921.3 4019.6 4019.9 4495.1 4501.6 4504.8 4529.2 4532.1 5302.4 5306.9 5321.8 5321.9 5327.9 1264.7 1287.8 1302.0 1323.3 1339.7 903.7 923.2 923.3 925.6 927.5 5000.2 5005.5 5024.8 5034.7 5042.6 5240.9 5244.9 5246.8 5263.2 5285.8 5231.0 5232.8 5236.6 5242.9 5243.9 2795.2 2796.9 2817.3 2835.9 2840.7 5302.9 5445.3 5490.4 5521.6 5540.2 5357.4 5385.9 5388.5 5401.5 5408.3 5422.5 5460.0 5502.9 5505.7 5530.2 5167.7 5261.8 5396.7 5428.9 5438.6 5408.2 5408.7 5408.9 5412.3 5431.2 5383.3 5628.7 5641.9 5683.3 5684.2 3849.2 3894.9 3904.6 3904.8 3910.8 5444.3 5448.8 5464.9 5466.5 5469.0 5282.0 5355.3 5366.9 5367.8 5421.3 5297.2 5303.7 5307.6 5315.5 5357.2 4979.4 5050.1 5144.5 5186.6 5239.6 5293.4 5295.7 5333.6 5338.3 5355.9 5085.3 5199.9 5213.9 5232.6 5241.8 09:08:03 INFO - PROCESS | 30201 | 09:08:03 INFO - PROCESS | 30201 | [#5] yui.html Cycles:180 Average:16308.41 Median:4319.26 stddev:20783.68 (481.2%) stddev-sans-first:20808.89 09:08:03 INFO - PROCESS | 30201 | Values: 690.8 749.9 755.8 785.7 810.6 12618.9 13368.8 13741.9 13872.4 14049.5 11121.3 12030.4 12159.9 12179.8 12184.6 767.1 779.5 782.1 782.2 782.5 776.0 778.1 781.6 784.3 788.1 826.0 826.5 826.6 827.5 828.0 813.5 818.6 818.7 819.0 819.4 599.5 601.4 601.6 605.7 608.3 12177.2 12197.5 12204.5 12347.1 12442.8 13975.5 14609.8 14654.9 14687.3 14703.6 11486.0 11506.6 11660.5 11688.6 11852.3 3600.3 3633.9 3636.4 3686.0 3810.2 47792.5 49010.5 70164.3 76678.2 77610.2 11085.9 11108.8 11154.0 11363.1 11625.8 20739.5 20753.9 20763.8 20779.2 20784.9 7851.8 7932.0 7965.2 8185.1 8284.4 4030.5 4264.7 4292.4 4346.1 4493.0 3471.4 3504.4 3541.4 3555.7 3563.2 582.2 596.0 596.7 601.2 607.1 559.8 656.0 675.2 697.4 709.1 8938.5 9237.3 9255.7 9438.8 9737.0 598.8 650.2 678.8 692.2 711.4 4084.1 4269.3 4279.7 4519.3 4545.4 74424.6 77998.6 78830.6 79405.6 81577.0 46826.1 47473.8 60082.7 67872.7 70345.3 59315.4 60373.4 61355.0 62402.0 62844.3 8941.2 9131.4 9284.4 9486.7 9511.2 38525.0 39501.6 40118.0 40128.8 40645.4 50494.2 50956.5 55460.7 57630.7 57930.0 11878.3 11944.5 12088.3 12272.1 12682.4 12634.7 12706.9 12715.5 12882.0 13032.2 12061.0 12257.3 12340.9 12505.9 12575.6 13103.9 13198.3 13454.8 13512.8 13543.7 12945.2 12968.6 13275.3 13305.4 13533.4 10122.2 10557.0 10678.1 10969.1 11098.8 10685.8 10728.5 11011.0 11015.7 11019.6 09:08:03 INFO - PROCESS | 30201 | -------- Summary: end -------- 09:08:03 INFO - PROCESS | 30201 | 09:08:04 INFO - TEST-INFO | 30201: exit 0 09:08:04 INFO - TEST-OK | dromaeo_css | took 1118647ms 09:08:04 INFO - TEST-START | kraken 09:08:04 INFO - operating with platform_type : linux_ 09:08:04 INFO - Initialising browser for kraken test... 09:08:04 INFO - TEST-INFO | started process 30504 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH7DjIh/profile http://localhost:49181/getInfo.html) 09:08:08 INFO - PROCESS | 30504 | __metrics Screen width/height:1600/1200 09:08:08 INFO - PROCESS | 30504 | colorDepth:24 09:08:08 INFO - PROCESS | 30504 | Browser inner width/height: 1024/697 09:08:08 INFO - PROCESS | 30504 | __metrics 09:08:08 INFO - PROCESS | 30504 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:08:08 INFO - PROCESS | 30504 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:08:09 INFO - PROCESS | 30504 | console.error: PushService: 09:08:09 INFO - PROCESS | 30504 | stateChangeProcessEnqueue: Error transitioning state 09:08:09 INFO - PROCESS | 30504 | UnknownError 09:08:09 INFO - TEST-INFO | 30504: exit 0 09:08:09 INFO - Browser initialized. 09:08:09 INFO - Running cycle 1/1 for kraken test... 09:08:09 INFO - TEST-INFO | started process 30589 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH7DjIh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:22 INFO - PROCESS | 30589 | RSS: Main: 172408832 09:08:22 INFO - PROCESS | 30589 | 09:08:24 INFO - PROCESS | 30589 | Cycle 1(1): loaded http://localhost:49181/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49181/tests/kraken/driver.html?testName=audio-beat-detection) 09:08:25 INFO - PROCESS | 30589 | RSS: Main: 234655744 09:08:25 INFO - PROCESS | 30589 | 09:08:28 INFO - PROCESS | 30589 | Cycle 1(1): loaded http://localhost:49181/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49181/tests/kraken/driver.html?testName=audio-dft) 09:08:28 INFO - PROCESS | 30589 | RSS: Main: 217182208 09:08:28 INFO - PROCESS | 30589 | 09:08:31 INFO - PROCESS | 30589 | Cycle 1(1): loaded http://localhost:49181/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49181/tests/kraken/driver.html?testName=audio-fft) 09:08:32 INFO - PROCESS | 30589 | RSS: Main: 298545152 09:08:32 INFO - PROCESS | 30589 | 09:08:34 INFO - PROCESS | 30589 | Cycle 1(1): loaded http://localhost:49181/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49181/tests/kraken/driver.html?testName=audio-oscillator) 09:08:35 INFO - PROCESS | 30589 | RSS: Main: 210268160 09:08:35 INFO - PROCESS | 30589 | 09:08:37 INFO - PROCESS | 30589 | Cycle 1(1): loaded http://localhost:49181/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49181/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:08:37 INFO - PROCESS | 30589 | RSS: Main: 213630976 09:08:37 INFO - PROCESS | 30589 | 09:08:41 INFO - PROCESS | 30589 | Cycle 1(1): loaded http://localhost:49181/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49181/tests/kraken/driver.html?testName=imaging-darkroom) 09:08:41 INFO - PROCESS | 30589 | RSS: Main: 253857792 09:08:41 INFO - PROCESS | 30589 | 09:08:44 INFO - PROCESS | 30589 | Cycle 1(1): loaded http://localhost:49181/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49181/tests/kraken/driver.html?testName=imaging-desaturate) 09:08:45 INFO - PROCESS | 30589 | RSS: Main: 252899328 09:08:45 INFO - PROCESS | 30589 | 09:08:48 INFO - PROCESS | 30589 | Cycle 1(1): loaded http://localhost:49181/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49181/tests/kraken/driver.html?testName=json-parse-financial) 09:08:48 INFO - PROCESS | 30589 | RSS: Main: 251854848 09:08:48 INFO - PROCESS | 30589 | 09:08:50 INFO - PROCESS | 30589 | Cycle 1(1): loaded http://localhost:49181/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49181/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:08:51 INFO - PROCESS | 30589 | RSS: Main: 214216704 09:08:51 INFO - PROCESS | 30589 | 09:08:52 INFO - PROCESS | 30589 | Cycle 1(1): loaded http://localhost:49181/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49181/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:08:53 INFO - PROCESS | 30589 | RSS: Main: 220180480 09:08:53 INFO - PROCESS | 30589 | 09:08:55 INFO - PROCESS | 30589 | Cycle 1(1): loaded http://localhost:49181/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49181/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:08:56 INFO - PROCESS | 30589 | RSS: Main: 230125568 09:08:56 INFO - PROCESS | 30589 | 09:08:58 INFO - PROCESS | 30589 | Cycle 1(1): loaded http://localhost:49181/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49181/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:08:59 INFO - PROCESS | 30589 | RSS: Main: 239972352 09:08:59 INFO - PROCESS | 30589 | 09:09:02 INFO - PROCESS | 30589 | Cycle 1(1): loaded http://localhost:49181/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49181/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:09:03 INFO - PROCESS | 30589 | RSS: Main: 236154880 09:09:03 INFO - PROCESS | 30589 | 09:09:04 INFO - PROCESS | 30589 | Cycle 1(1): loaded http://localhost:49181/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49181/tests/kraken/driver.html?testName=ai-astar) 09:09:05 INFO - PROCESS | 30589 | RSS: Main: 286932992 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | __start_tp_report 09:09:05 INFO - PROCESS | 30589 | _x_x_mozilla_page_load 09:09:05 INFO - PROCESS | 30589 | _x_x_mozilla_page_load_details 09:09:05 INFO - PROCESS | 30589 | |i|pagename|runs| 09:09:05 INFO - PROCESS | 30589 | |0;ai-astar;111;111;113;111;111;111;112;112;111;111 09:09:05 INFO - PROCESS | 30589 | |1;audio-beat-detection;144;125;122;154;140;126;125;124;149;122 09:09:05 INFO - PROCESS | 30589 | |2;audio-dft;161;164;160;160;161;162;163;160;163;160 09:09:05 INFO - PROCESS | 30589 | |3;audio-fft;75;76;78;109;77;75;76;75;90;75 09:09:05 INFO - PROCESS | 30589 | |4;audio-oscillator;84;85;105;84;84;84;105;84;86;110 09:09:05 INFO - PROCESS | 30589 | |5;imaging-gaussian-blur;127;127;127;127;127;127;127;128;128;128 09:09:05 INFO - PROCESS | 30589 | |6;imaging-darkroom;146;146;146;146;145;146;146;145;145;145 09:09:05 INFO - PROCESS | 30589 | |7;imaging-desaturate;95;96;95;95;95;94;95;96;96;95 09:09:05 INFO - PROCESS | 30589 | |8;json-parse-financial;53;53;53;54;55;55;53;54;53;55 09:09:05 INFO - PROCESS | 30589 | |9;json-stringify-tinderbox;62;63;63;66;63;63;64;63;63;64 09:09:05 INFO - PROCESS | 30589 | |10;stanford-crypto-aes;81;81;87;81;82;81;81;81;86;81 09:09:05 INFO - PROCESS | 30589 | |11;stanford-crypto-ccm;142;132;142;140;141;131;132;132;181;153 09:09:05 INFO - PROCESS | 30589 | |12;stanford-crypto-pbkdf2;172;168;166;170;171;170;173;165;173;170 09:09:05 INFO - PROCESS | 30589 | |13;stanford-crypto-sha256-iterative;63;70;66;66;68;63;63;70;63;63 09:09:05 INFO - PROCESS | 30589 | __end_tp_report 09:09:05 INFO - PROCESS | 30589 | __start_cc_report 09:09:05 INFO - PROCESS | 30589 | _x_x_mozilla_cycle_collect,1695 09:09:05 INFO - PROCESS | 30589 | __end_cc_report 09:09:05 INFO - PROCESS | 30589 | __startTimestamp1481735345035__endTimestamp 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | ------- Summary: start ------- 09:09:05 INFO - PROCESS | 30589 | Number of tests: 14 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | [#0] ai-astar Cycles:10 Average:111.40 Median:111.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 09:09:05 INFO - PROCESS | 30589 | Values: 111.0 111.0 113.0 111.0 111.0 111.0 112.0 112.0 111.0 111.0 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | [#1] audio-beat-detection Cycles:10 Average:133.10 Median:133.00 stddev:12.32 (9.3%) stddev-sans-first:12.42 09:09:05 INFO - PROCESS | 30589 | Values: 144.0 125.0 122.0 154.0 140.0 126.0 125.0 124.0 149.0 122.0 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.51 (0.9%) stddev-sans-first:1.59 09:09:05 INFO - PROCESS | 30589 | Values: 161.0 164.0 160.0 160.0 161.0 162.0 163.0 160.0 163.0 160.0 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | [#3] audio-fft Cycles:10 Average:80.60 Median:76.00 stddev:10.97 (14.4%) stddev-sans-first:11.44 09:09:05 INFO - PROCESS | 30589 | Values: 75.0 76.0 78.0 109.0 77.0 75.0 76.0 75.0 90.0 75.0 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:84.00 stddev:10.85 (12.9%) stddev-sans-first:11.20 09:09:05 INFO - PROCESS | 30589 | Values: 84.0 85.0 105.0 84.0 84.0 84.0 105.0 84.0 86.0 110.0 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | [#5] imaging-gaussian-blur Cycles:10 Average:127.30 Median:127.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:09:05 INFO - PROCESS | 30589 | Values: 127.0 127.0 127.0 127.0 127.0 127.0 127.0 128.0 128.0 128.0 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:09:05 INFO - PROCESS | 30589 | Values: 146.0 146.0 146.0 146.0 145.0 146.0 146.0 145.0 145.0 145.0 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 09:09:05 INFO - PROCESS | 30589 | Values: 95.0 96.0 95.0 95.0 95.0 94.0 95.0 96.0 96.0 95.0 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:0.92 (1.7%) stddev-sans-first:0.93 09:09:05 INFO - PROCESS | 30589 | Values: 53.0 53.0 53.0 54.0 55.0 55.0 53.0 54.0 53.0 55.0 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | [#9] json-stringify-tinderbox Cycles:10 Average:63.40 Median:63.00 stddev:1.07 (1.7%) stddev-sans-first:1.01 09:09:05 INFO - PROCESS | 30589 | Values: 62.0 63.0 63.0 66.0 63.0 63.0 64.0 63.0 63.0 64.0 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:81.00 stddev:2.30 (2.8%) stddev-sans-first:2.40 09:09:05 INFO - PROCESS | 30589 | Values: 81.0 81.0 87.0 81.0 82.0 81.0 81.0 81.0 86.0 81.0 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | [#11] stanford-crypto-ccm Cycles:10 Average:142.60 Median:141.50 stddev:15.15 (10.7%) stddev-sans-first:16.06 09:09:05 INFO - PROCESS | 30589 | Values: 142.0 132.0 142.0 140.0 141.0 131.0 132.0 132.0 181.0 153.0 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:170.50 stddev:2.74 (1.6%) stddev-sans-first:2.79 09:09:05 INFO - PROCESS | 30589 | Values: 172.0 168.0 166.0 170.0 171.0 170.0 173.0 165.0 173.0 170.0 09:09:05 INFO - PROCESS | 30589 | 09:09:05 INFO - PROCESS | 30589 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:2.95 (4.5%) stddev-sans-first:2.99 09:09:05 INFO - PROCESS | 30589 | Values: 63.0 70.0 66.0 66.0 68.0 63.0 63.0 70.0 63.0 63.0 09:09:05 INFO - PROCESS | 30589 | -------- Summary: end -------- 09:09:05 INFO - PROCESS | 30589 | 09:09:06 INFO - TEST-INFO | 30589: exit 0 09:09:06 INFO - TEST-OK | kraken | took 61989ms 09:09:06 INFO - SUITE-END | took 1180s 09:09:06 INFO - Completed test suite (00:19:41) 09:09:06 INFO - javascript benchmark 09:09:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3228.11226912599, 3268.57508523892, 3290.4406250937336, 3298.983505082474, 3302.4220761366755, 5185.688858668483, 5244.580433565315, 5301.072312345344, 5310.8141215057485, 5322.973385133074, 5279.392869819972, 5318.228856815759, 5414.729263536827, 5417.133258678612, 5439.238506609087, 4815.374001379833, 4818.253170758538, 4848.53938875808, 4873.025394921012, 4884.438289596706, 4881.658283920127, 4951.826686065989, 4994.950050499485, 5003.899922001576, 5045.470225626312, 4933.7286449245275, 4938.2839488274, 4984.152694042022, 5002.749862506878, 5022.422421421528, 4685.695429797052, 4718.9292160617615, 4721.57505824474, 4769.475357710649, 4774.021325628238, 4731.952680473186, 4739.099571081483, 4742.857714268577, 4744.762761861927, 4751.382320298374, 5093.567046801027, 5147.356580427447, 5149.588032957355, 5149.613778966555, 5149.897002059938, 5666.886662266773, 5685.658860468385, 5716.514096301819, 5731.684757338345, 5802.854928626776, 5751.511121554631, 5758.510526605243, 5773.624714393593, 5784.652920824764, 5790.536757059425, 5183.896322073538, 5343.919841202385, 5391.973040134813, 5397.54120899724, 5398.24424580559, 5627.324721033502, 5653.830385088454, 5699.316082070178, 5733.684647344353, 5839.591228613956, 5553.055980483327, 5557.722113894289, 5560.165975103686, 5560.666360018412, 5565.860853478695, 5616.719164041781, 5633.352164501135, 5640.943590564042, 5644.604877658607, 5644.604877658607, 5493.615446918758, 5534.1975413564805, 5582.553395728413, 5608.383077861432, 5628.690422026746, 5620.9437905621235, 5625.156226566048, 5627.915581266284, 5641.746121424546, 5673.801416950387, 4355.4337936068105, 4356.956430435718, 4363.47638283408, 4369.388285640013, 4401.163778882003, 3879.573246942834, 3900.7659540427667, 3909.237698648736, 3954.8220330085214, 3975.045988962628, 5381.0583147948955, 5430.755615997289, 5434.809781657623, 5477.808276710376, 5482.451754824486, 5682.0, 5718.342390625048, 5725.112607545837, 5730.455606717355, 5735.598508104393, 5179.067767801832, 5259.60552958522, 5427.48438898304, 5431.864203394868, 5482.342118945672, 4822.95177048225, 4834.8791280218275, 4842.612590992712, 4843.15244832153, 4844.660873738804, 5740.598158128974, 5743.483086522233, 5743.597948143673, 5746.195532625352, 5746.195532625436, 5545.473180047889, 5547.694876781654, 5554.611177217556, 5557.833265002057, 5557.972210138923, 5753.1370294456165, 5787.508061814793, 5789.9710501447225, 5800.56495762811, 5805.158252053513, 5571.387147413862, 5579.944200557942, 5583.050881350261, 5585.1901474286815, 5586.273784408001, 5610.242617246783, 5612.298462692163, 5617.466340697627, 5628.521575665951, 5636.9718151410625, 5502.944970550243, 5549.944500555104, 5554.861253442954, 5563.13771365439, 5568.49883510486, 5508.779648814003, 5512.118061110364, 5517.944820551904, 5524.91712624319, 5602.159676048625, 5234.842954711365, 5257.0800109981365, 5258.290130832289, 5310.8141215057285, 5314.468553144655, 5430.619856609999, 5469.15228139639, 5473.808416705396, 5473.8357849264585, 5596.496315331639, 5479.0411677957, 5527.8894422112135, 5531.612787104864, 5557.3331200256225, 5583.832485025456], "value": 5226.413950019458, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.5920439272742, 569.1491220625167, 578.9607654260602, 588.0150747497939, 600.2586805295457, 30605.234869128257, 38407.42388864166, 39896.40155397668, 40008.79995600025, 43357.7832110839, 41506.16987660245, 41744.37383439251, 41784.791076044574, 41796.58203417965, 41888.16223675532, 8544.999999999984, 8725.301975841918, 8726.781830454258, 8732.781680457976, 8735.650573977033, 7619.466637335388, 7819.2180781921925, 9937.304388692794, 10160.441175735332, 10164.237682173829, 10237.590496380173, 10299.227557933147, 10309.381437113798, 10319.845202321934, 10321.483925803717, 10158.6952391428, 10203.948980255125, 10210.336328138721, 10234.437105959169, 10236.53935572901, 10000.749981250454, 10053.145482633985, 10062.79874402515, 10073.043060909202, 10089.344192627455, 39353.212935741125, 39458.80270598629, 39495.40756888649, 39579.8021009896, 39629.207415851524, 3226.2095786532213, 3256.1045712429272, 3266.6406695263504, 3288.57248557686, 3341.7493687973256, 29536.70463295361, 29748.553771693227, 30129.397412051854, 31222.375552488822, 31790.841045794856, 4111.691623128278, 4165.645920096796, 4183.769892655902, 4217.578242175789, 4284.335927931175, 313976.0, 314315.0, 315562.1325440939, 315619.0, 315677.0, 53211.46788532065, 53452.0, 53605.463945360825, 53621.73189134107, 53647.6588085301, 56577.43422565804, 56730.716346418005, 56872.0, 56882.7155864218, 56993.43006569964, 14387.64030899215, 14808.481703140338, 14840.109593424213, 14856.331465084097, 14873.0, 10639.414832184304, 10900.40047797363, 11752.823707644393, 11767.940885320362, 11814.05487560993, 14616.707665846623, 14822.62943426423, 15052.397904083933, 15394.615134621725, 15534.533963981099, 5503.9174412383845, 5620.747066382022, 5621.116925829811, 5664.093745000862, 5773.134029895549, 849.9702510412229, 899.3884158772098, 910.2217603948528, 970.1947383671536, 1029.3206483720858, 29935.850320748694, 29980.128789350332, 30083.117971642034, 30110.849445752632, 30653.846730766203, 1005.9547320370601, 1014.5333146752577, 1071.0, 1079.740862193068, 1100.1034157162048, 15104.773428398583, 15748.527544173694, 15903.363865445484, 15986.60033499172, 16010.359585616678, 440953.0, 445248.0, 445418.0, 445624.0, 446233.0, 54675.453245467834, 54849.45150548444, 54915.17627235515, 54963.175552366745, 55010.17484737732, 1152.8097863852538, 1216.4282787090053, 1246.4079562207878, 1297.0686249076966, 1317.5520323090195, 27456.862715686293, 30745.692543075176, 31512.212194695316, 31624.680249629164, 31676.683233168296, 47057.0, 47642.761786190844, 47747.52252477431, 47759.52240477551, 47808.282875757584, 283951.0, 284914.0, 286123.0, 286589.0, 286597.0, 23763.881180593988, 24395.634065488677, 24458.87770561207, 25854.095106671175, 38286.80856595699, 39145.41281880831, 39427.21145577015, 39838.0, 40492.797536012135, 40864.0, 30597.23506912345, 30825.0, 31323.216919577193, 31713.841430793622, 32135.357292853543, 33829.0, 34395.656043439245, 34531.0, 34554.82722586371, 35182.82408588043, 33466.0, 34197.145071373416, 34364.0, 34372.0, 34942.65057349394, 29845.70154298429, 31228.53157202689, 31633.20916977094, 31712.682873170972, 31717.841410792797, 24002.39994000164, 24311.784410779743, 24434.63348049745, 24616.753832461447, 24837.751622483545], "value": 18369.827183323512, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.1636114228707, 365.51021631014453, 373.30006238303173, 377.48661819924905, 391.53211911765436, 14017.088889222206, 17173.313067477302, 17406.999999999985, 18043.0, 18995.52511187221, 17582.472525824247, 17907.73138402922, 18002.0, 18044.17660681825, 18213.90893045533, 5218.582513398919, 5403.081476149064, 5432.91850622241, 5439.5104440600335, 5450.754716037788, 3986.900327491807, 4189.0, 5446.509814116729, 5560.6941618210985, 5561.332640083196, 4183.1215444756635, 6050.0, 6147.47746441553, 6147.8770424591485, 6152.0156774915795, 5853.5902486825935, 6014.51883849291, 6138.938610613926, 6141.17094192285, 6146.446819786218, 5304.92042619361, 5334.973325133349, 5335.866603334909, 5348.304720386325, 5350.678959262456, 19342.226310947546, 20124.496887577927, 20287.087081081238, 20644.96775161236, 20899.89550052255, 2132.17911104226, 2148.323278167388, 2221.311393468014, 2482.8262021658493, 2579.8323108997856, 9257.814843703089, 9295.72112836616, 9336.0, 9532.666356677482, 9777.902220977841, 1821.8633602479736, 1848.1867978089597, 1865.766779152606, 1922.5001499610062, 1980.485073880787, 70760.0, 76942.0, 77151.0, 77720.0, 79620.20379796186, 10110.140638045848, 10366.274360794823, 10478.161747060218, 10639.308444951053, 10712.196585256137, 14465.132092074298, 14526.0, 14532.982691211513, 14537.345819438151, 14563.563093107225, 7490.0, 7763.835424686229, 7786.961065194638, 7937.2459616336355, 7959.363250939911, 7401.407887369105, 7402.370798482081, 7447.88828167578, 7606.353459955855, 7632.542047477169, 4350.651947844165, 4351.456067991501, 4511.142882852248, 4535.569120933506, 4622.375979242826, 1850.6113716119496, 1853.3513270355268, 1864.794872564017, 1880.7084901840237, 1926.6243082598758, 905.8278927003848, 912.8630705394244, 949.0414681171928, 981.5337714585514, 1013.5641674080216, 16558.917205414138, 16768.41310554149, 16907.746383804253, 17929.641407172043, 18189.54526136831, 945.7257395355407, 966.1160038564634, 967.8693376394237, 974.9853752193723, 992.4591097851688, 9465.384749991228, 9923.305368624127, 9970.102690757867, 10001.399916005063, 10002.449865257335, 266884.0, 270987.0, 408426.0, 411115.0, 411832.0534357244, 86904.5654771722, 87713.0, 87882.0, 88561.11438885657, 88744.5562772182, 945.716285114463, 972.9562169702522, 983.1299300119302, 990.980180396388, 996.8106059848606, 15747.842521574637, 15776.447824326093, 16324.755128672841, 16518.25667844926, 16784.07687577195, 34465.82767086149, 35914.4612830813, 36393.63606363902, 37531.812340939214, 37605.81197093997, 51113.23330150125, 52165.47834521758, 52300.939373184134, 53297.46702532925, 53869.0, 7489.775306740807, 7794.493357931717, 7841.098273698598, 7841.882371764314, 7894.210578942289, 8782.956085219534, 11409.60066397672, 12091.093168012258, 12462.875371246171, 12741.93629031886, 10800.405977671304, 11081.335119892832, 11119.72200694989, 11150.888491115309, 11168.274062185932, 10575.153987680813, 10578.365298082139, 10786.676399708022, 10933.289336193122, 11067.83398249043, 10961.397123157984, 11040.668779936615, 11113.722156946142, 11187.272827266203, 11242.55029798799, 6624.171978502687, 6830.624315662686, 6876.69054892521, 7111.324424179695, 7636.694532218649, 9105.0, 10329.638462653771, 10466.842997355197, 10612.734681633021, 11308.717282068013], "value": 8543.863150683037, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.497071112977, 1397.8951578631602, 1415.4691990503559, 1544.582962600098, 1744.607463320753, 7385.593792341419, 8113.91886081139, 8377.664893404271, 8400.49597024179, 8626.266767324785, 8417.0, 8494.150584941503, 8497.0653228145, 8498.532580708059, 8500.234978851902, 1889.914953827081, 1957.8042195780379, 2151.3868547464017, 2184.2682701295084, 2185.1477923609805, 2128.329576183505, 2209.5969059647077, 2209.884008575675, 2216.5012872103807, 2219.4784225706944, 2181.738191417024, 2181.967270490944, 2189.255653077953, 2193.166596693254, 2231.464448532357, 2142.2930432957164, 2158.0396723458066, 2177.8475506714535, 2178.6078505868936, 2178.8256939444805, 2110.335244398025, 2130.872147671145, 2133.637281662121, 2137.818285445739, 2142.6893100500342, 8293.751187464386, 8461.111583283728, 8461.323094152514, 8471.788205294855, 8474.915250847536, 4951.826686066006, 5089.694618322913, 5100.234964755279, 5105.770240339157, 5110.514501122388, 3179.3959147761852, 3305.3719793239447, 3423.6576342365815, 3425.8972230833115, 3434.553508043963, 1057.8148823955853, 1060.4008735064663, 1060.973475663107, 1073.7637719701731, 1161.6340852631479, 10945.507452164671, 11269.492872820743, 11293.548258069668, 11694.532218711242, 11720.179587428884, 4080.2655522006326, 4108.4864391951005, 4222.78886055696, 4231.767252801125, 4269.786510674454, 9141.588628511732, 9142.405743626643, 9145.314101442418, 9152.359334846496, 9153.496557689392, 5049.141645920203, 5087.949120508822, 5089.389273287229, 5090.312807770978, 5090.949090509122, 4125.608067233668, 4150.709450338508, 4589.35748995141, 4755.952440475551, 4756.857294281177, 3730.515033045741, 3751.6811071059265, 3851.8266677998995, 3876.8643097491454, 3896.383001054587, 1225.8099994500687, 1243.8383010208795, 1250.487300206929, 1252.987470125287, 1256.0830593666674, 885.8759773631568, 923.4297969943801, 942.990570094304, 949.2453499467939, 970.4516947924541, 5465.4261302563455, 5650.943490565124, 5754.798582049608, 5918.171455996164, 5927.8221653350465, 902.3638334973828, 932.2029664636638, 936.3773090894479, 937.4094320578129, 942.119118624075, 3761.39817629178, 3792.867249646304, 3850.2876967761053, 3906.140649057203, 4088.4889388826396, 10208.897911020795, 10306.227032972558, 10376.584936602603, 10386.584536618602, 10398.532066057045, 9704.660336888175, 10321.84517232242, 10324.174066074696, 10329.74175645615, 10333.844992325121, 7484.32641062307, 7637.465377423527, 7667.693292268247, 7668.386529077772, 7679.961600192075, 5292.0, 5642.7460764266525, 5649.180868773922, 5682.721387687737, 5998.550108741949, 9444.574994125418, 9494.572744226665, 9522.23822094217, 9547.52262386864, 9622.566984485575, 9423.151916327564, 9426.62293508252, 9589.712308630753, 9605.903940960501, 9615.9038409615, 7271.091113610799, 7484.476086674098, 7497.212792656911, 7533.0, 7543.245675432413, 7884.4086693497065, 7971.0, 8013.998250218722, 8026.919730802617, 8059.919400805917, 7192.244814294633, 7293.781186564412, 7352.558846469229, 7357.411407087528, 7494.625268736432, 7187.173475050227, 7285.927140728525, 7304.671289791866, 7316.707331706672, 7324.926750732425, 6092.208012958287, 6172.629642221481, 6179.783707570214, 6188.876222475616, 6190.473809726223, 4610.0779844031995, 4613.884652883705, 4665.976670116627, 4666.766661666835, 4669.5797378234765, 4623.768811559476, 4632.814687412466, 4658.440987081572, 4750.691205071659, 4844.806207751651], "value": 4376.178135933267, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.215116424106, 366.91744357519553, 371.31863031337485, 373.4305184593493, 374.6216321515272, 4979.302897594331, 5071.594272458204, 5080.542751152396, 5148.948510514893, 5157.329547158869, 4908.748269191351, 5037.546620804127, 5057.797688092481, 5061.291419201315, 5093.6943783372935, 4091.1975296921705, 4096.774677392742, 4100.56944020878, 4103.917921641565, 4104.240715467633, 3774.981125094371, 3820.789856557888, 4084.2239974404915, 4092.5088989321175, 4097.549269580344, 4007.9599204008023, 4033.5159780826343, 4049.3318602430563, 4060.8984775380554, 4066.7763273019973, 4024.55729869717, 4031.1937612477623, 4037.515498140242, 4062.146949140669, 4067.3898915162667, 3636.8363423646, 3639.636036396366, 3640.908977275563, 3667.376545987162, 3675.246574452231, 4734.502877197879, 4928.827491037797, 4944.53026962438, 4953.504649535053, 4957.182064959314, 4485.955140448619, 4490.236659767815, 4495.685302028859, 4502.099580083964, 4502.527234640348, 5292.391374991848, 5295.867603309909, 5297.576193904479, 5302.628815982883, 5317.441668624816, 3636.745427820027, 3815.961840381616, 3834.3098242316373, 3851.768893866377, 3855.9807200964096, 5480.972595136999, 5495.818399044185, 5501.367342755594, 5508.641938274, 5510.283663123809, 3934.2721596504166, 3941.049180655594, 3948.861789837342, 3954.6638535724787, 3957.861474848424, 3910.393888947218, 3919.392494163409, 3921.2941670499586, 4019.618136277049, 4019.8995025124605, 4495.056038232008, 4501.582001669463, 4504.77476126199, 4529.230030894756, 4532.076810457236, 5302.416734159239, 5306.946930530722, 5321.840344789663, 5321.882404695058, 5327.920081198785, 1264.7027948432112, 1287.8454585449806, 1301.9934900325627, 1323.3184909771476, 1339.70049052419, 903.746950853762, 923.1645360948353, 923.2890674180843, 925.6343744220995, 927.5176908007799, 5000.199968005175, 5005.4744251853035, 5024.834354356805, 5034.672746271481, 5042.571381432546, 5240.9213861792105, 5244.868878277997, 5246.790128394898, 5263.184206448007, 5285.814996475182, 5231.032259032091, 5232.790688372498, 5236.607254455931, 5242.947570524322, 5243.947560524422, 2795.2313113893842, 2796.860156992142, 2817.3238422778386, 2835.8582070896373, 2840.6733225679313, 5302.946970530323, 5445.264889239981, 5490.40778174333, 5521.585881058937, 5540.196671482612, 5357.357117145967, 5385.919211211835, 5388.5419739321815, 5401.540869026098, 5408.296921400193, 5422.53908417781, 5460.0, 5502.889942201214, 5505.6696598204235, 5530.225768392509, 5167.715775632376, 5261.763220655156, 5396.676199428047, 5428.918566221589, 5438.592105592017, 5408.242846001642, 5408.72956352173, 5408.917566736756, 5412.296401467942, 5431.23962645222, 5383.300170977748, 5628.746706398304, 5641.8589535261945, 5683.261176047087, 5684.204211410323, 3849.191669749384, 3894.863679771308, 3904.590018048064, 3904.7657140571655, 3910.8044597770568, 5444.292242008513, 5448.809291674773, 5464.9453505464435, 5466.508014278575, 5469.0, 5281.973590132178, 5355.277037599874, 5366.892662146814, 5367.838964831061, 5421.2681288025615, 5297.231901374356, 5303.708296043755, 5307.601929855354, 5315.468453154654, 5357.1696387060065, 4979.427365852828, 5050.065737838511, 5144.459831717616, 5186.6369354144845, 5239.633225674319, 5293.385517417225, 5295.729025034041, 5333.573314134782, 5338.265063854185, 5355.892882142413, 5085.313482679939, 5199.896002080013, 5213.879016011576, 5232.607554433509, 5241.816536421359], "value": 4070.49086084337, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.7513295213722, 749.8875168724694, 755.7543798265561, 785.7407055671629, 810.6311628209163, 12618.936905315471, 13368.799468007976, 13741.931290343548, 13872.4451021959, 14049.50826721065, 11121.27711698739, 12030.398480076004, 12159.939200304008, 12179.75640487192, 12184.573539926105, 767.1101522234195, 779.5089093870854, 782.0693374883898, 782.1669921533577, 782.5422128055095, 775.9612019399036, 778.1012930065783, 781.6052893288881, 784.2784638132903, 788.1487992967582, 825.983480330393, 826.5454000299841, 826.6156237349632, 827.5283088639478, 827.9586020698971, 813.5037627047496, 818.6111596991441, 818.7339114787688, 819.0335404223044, 819.4304958054155, 599.4994179859822, 601.3865856826013, 601.6390165900468, 605.7274226598048, 608.3126067543648, 12177.208481448677, 12197.512099516009, 12204.511819527208, 12347.135700500969, 12442.751144977141, 13975.510857120054, 14609.78085328721, 14654.926725366411, 14687.26563671812, 14703.63240918975, 11486.0, 11506.597269095626, 11660.475278612483, 11688.590899318484, 11852.34812085335, 3600.2979419013304, 3633.927321453556, 3636.436352365361, 3685.963140368589, 3810.237952409529, 47792.5220747795, 49010.50989490095, 70164.29835701629, 76678.23321766822, 77610.22389776086, 11085.944570277097, 11108.777824443467, 11154.040315687815, 11363.090952723762, 11625.76748465026, 20739.481512961996, 20753.896230518752, 20763.79236207649, 20779.168833246502, 20784.896075519526, 7851.803704907423, 7932.0, 7965.203479651988, 8185.140560241208, 8284.420090593601, 4030.496187976503, 4264.701470897069, 4292.441982542249, 4346.087321662486, 4493.0115374617535, 3471.392506311385, 3504.369213541587, 3541.38025845476, 3555.715542756573, 3563.198280386944, 582.1820342418908, 596.0463258785994, 596.7314708381246, 601.1703848688782, 607.0742118269674, 559.7956745787758, 655.9639219842859, 675.1864003875319, 697.3549466743242, 709.1029847243242, 8938.50838203892, 9237.30720195988, 9255.676051338305, 9438.811223775487, 9736.95131524338, 598.7844376024627, 650.1840190560911, 678.8438659108433, 692.2316228985821, 711.3633298198084, 4084.1219137885496, 4269.274223382033, 4279.743215407086, 4519.254323036662, 4545.431821022372, 74424.62787686136, 77998.6100069496, 78830.60584697155, 79405.60297198592, 81577.0, 46826.06347873023, 47473.76263118732, 60082.69958650179, 67872.66063669749, 70345.29654703387, 59315.40684593185, 60373.396266036776, 61355.0, 62402.0, 62844.28667849838, 8941.239994600532, 9131.360804743605, 9284.396514226553, 9486.715398538056, 9511.239100871775, 38525.0, 39501.604983949794, 40118.0, 40128.79935600303, 40645.39031914583, 50494.24258636197, 50956.49043509518, 55460.72962334399, 57630.7118464405, 57930.0, 11878.287302761863, 11944.522219111139, 12088.318120992973, 12272.140950133406, 12682.365881705693, 12634.747305053663, 12706.87293127057, 12715.503236610006, 12882.0, 13032.218066916015, 12061.0, 12257.325847078497, 12340.938295308466, 12505.874941250471, 12575.559855405383, 13103.868961310645, 13198.328191859962, 13454.798178027533, 13512.797308040585, 13543.661408464866, 12945.158564693265, 12968.629114777312, 13275.269860157785, 13305.401256943655, 13533.390997405337, 10122.190224781853, 10556.972683820737, 10678.14144564237, 10969.122470202525, 11098.833517497082, 10685.839712404164, 10728.47417699779, 11011.0, 11015.669529914116, 11019.559217631206], "value": 6815.441456013658, "unit": "score"}], "name": "dromaeo_css", "value": 6808.329527901621, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 113.0, 111.0, 111.0, 111.0, 112.0, 112.0, 111.0, 111.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 125.0, 122.0, 154.0, 140.0, 126.0, 125.0, 124.0, 149.0, 122.0], "value": 133.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 160.0, 160.0, 161.0, 162.0, 163.0, 160.0, 163.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 78.0, 109.0, 77.0, 75.0, 76.0, 75.0, 90.0, 75.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 105.0, 84.0, 84.0, 84.0, 105.0, 84.0, 86.0, 110.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 127.0, 127.0, 127.0, 127.0, 127.0, 127.0, 128.0, 128.0, 128.0], "value": 127.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 94.0, 95.0, 96.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 54.0, 55.0, 55.0, 53.0, 54.0, 53.0, 55.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 66.0, 63.0, 63.0, 64.0, 63.0, 63.0, 64.0], "value": 63.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 87.0, 81.0, 82.0, 81.0, 81.0, 81.0, 86.0, 81.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 132.0, 142.0, 140.0, 141.0, 131.0, 132.0, 132.0, 181.0, 153.0], "value": 142.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 168.0, 166.0, 170.0, 171.0, 170.0, 173.0, 165.0, 173.0, 170.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 66.0, 66.0, 68.0, 63.0, 63.0, 70.0, 63.0, 63.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1523.0, "alertThreshold": 2.0}]} 09:09:06 INFO - Return code: 0 09:09:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:09:06 INFO - # TBPL SUCCESS # 09:09:06 INFO - Running post-action listener: _resource_record_post_action 09:09:06 INFO - [mozharness: 2016-12-14 17:09:06.879770Z] Finished run-tests step (success) 09:09:06 INFO - Running post-run listener: _resource_record_post_run 09:09:06 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 398249984; Read time: 16; Write time: 274284 09:09:06 INFO - TinderboxPrint: CPU usage
13.2% 09:09:06 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 09:09:06 INFO - TinderboxPrint: I/O write bytes / time
398,249,984 / 274,284 09:09:06 INFO - TinderboxPrint: CPU idle
8,299.3 (86.8%) 09:09:06 INFO - TinderboxPrint: CPU user
1,208.9 (12.6%) 09:09:06 INFO - TinderboxPrint: Swap in / out
0 / 0 09:09:06 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110419968; Read time: 0; Write time: 67784 09:09:06 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 287830016; Read time: 0; Write time: 206500 09:09:07 INFO - Running post-run listener: _upload_blobber_files 09:09:07 INFO - Blob upload gear active. 09:09:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:09:07 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 09:09:07 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-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:09:07 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-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:09:07 INFO - (blobuploader) - INFO - Open directory for files ... 09:09:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:09:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:09:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:12 INFO - (blobuploader) - INFO - Done attempting. 09:09:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:09:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:09:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:13 INFO - (blobuploader) - INFO - Done attempting. 09:09:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:09:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:09:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:14 INFO - (blobuploader) - INFO - Done attempting. 09:09:14 INFO - (blobuploader) - INFO - Iteration through files over. 09:09:14 INFO - Return code: 0 09:09:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:09:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:09:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/94cbf75375b6dce4353c4bcda9cead02c5f12974522bdaa8ea6a1ba8c14c8160dc4f49ffe3fd3856fd7597e302f2718249ee236e7529b6e6c0d6f2a304557014", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/df3a745858aa491c9b72e7e821639245ccf2f5931caf0159b034d4d7ea80cb880bda3aca4449a7f9c453d233f92c102f3c603ccc5fbab7692cc9bc558cf6c3c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc8a2d35963818546b50dbe44aef6fe99d0777a4b0a287dc846591fb391f828295d9d46764aaf7c79f72c7b0bb56c42a1cda3c175d0529787304889ec26bb578"} 09:09:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:09:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:09:14 INFO - Contents: 09:09:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/94cbf75375b6dce4353c4bcda9cead02c5f12974522bdaa8ea6a1ba8c14c8160dc4f49ffe3fd3856fd7597e302f2718249ee236e7529b6e6c0d6f2a304557014", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/df3a745858aa491c9b72e7e821639245ccf2f5931caf0159b034d4d7ea80cb880bda3aca4449a7f9c453d233f92c102f3c603ccc5fbab7692cc9bc558cf6c3c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc8a2d35963818546b50dbe44aef6fe99d0777a4b0a287dc846591fb391f828295d9d46764aaf7c79f72c7b0bb56c42a1cda3c175d0529787304889ec26bb578"} 09:09:14 INFO - Running post-run listener: copy_logs_to_upload_dir 09:09:14 INFO - Copying logs to upload dir... 09:09:14 INFO - mkdir: /builds/slave/test/build/upload/logs 09:09:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.378985 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-14 09:09:14.059706) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 09:09:14.063094) ========= 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=1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369 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-Inbound/sha512/94cbf75375b6dce4353c4bcda9cead02c5f12974522bdaa8ea6a1ba8c14c8160dc4f49ffe3fd3856fd7597e302f2718249ee236e7529b6e6c0d6f2a304557014", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/df3a745858aa491c9b72e7e821639245ccf2f5931caf0159b034d4d7ea80cb880bda3aca4449a7f9c453d233f92c102f3c603ccc5fbab7692cc9bc558cf6c3c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc8a2d35963818546b50dbe44aef6fe99d0777a4b0a287dc846591fb391f828295d9d46764aaf7c79f72c7b0bb56c42a1cda3c175d0529787304889ec26bb578"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011951 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481726174/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/94cbf75375b6dce4353c4bcda9cead02c5f12974522bdaa8ea6a1ba8c14c8160dc4f49ffe3fd3856fd7597e302f2718249ee236e7529b6e6c0d6f2a304557014", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/df3a745858aa491c9b72e7e821639245ccf2f5931caf0159b034d4d7ea80cb880bda3aca4449a7f9c453d233f92c102f3c603ccc5fbab7692cc9bc558cf6c3c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc8a2d35963818546b50dbe44aef6fe99d0777a4b0a287dc846591fb391f828295d9d46764aaf7c79f72c7b0bb56c42a1cda3c175d0529787304889ec26bb578"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 09:09:14.089216) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 09:09:14.089701) ========= 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=1f5564757af27664ca02d71f000001b0-1481734112.431457-1803278369 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004458 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 09:09:14.172427) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 09:09:14.172747) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 09:09:14.173034) ========= ========= Total master_lag: 0.17 =========