builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-027 starttime: 1482642476.63 results: success (0) buildid: 20161224193118 builduid: 685d4dc036184713b1b435aa061e3f3e revision: 1156db49e976173fc3cf90d2126456fd1e2bae4b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-24 21:07:56.628762) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-24 21:07:56.629126) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-24 21:07:56.629390) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-24 21:07:56.644051) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 21:07:56.644319) ========= 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-1482640188.252823-602346170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-24 21:07:57-- 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% 35.5M=0s 2016-12-24 21:07:57 (35.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130815 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 21:07:56.799343) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 21:07:56.799738) ========= 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-1482640188.252823-602346170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015117 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 21:07:56.851129) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 21:07:56.851721) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1156db49e976173fc3cf90d2126456fd1e2bae4b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1156db49e976173fc3cf90d2126456fd1e2bae4b --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-1482640188.252823-602346170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-24 21:07:57,395 truncating revision to first 12 chars 2016-12-24 21:07:57,395 Setting DEBUG logging. 2016-12-24 21:07:57,395 attempt 1/10 2016-12-24 21:07:57,395 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1156db49e976?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-24 21:07:57,686 unpacking tar archive at: mozilla-central-1156db49e976/testing/mozharness/ program finished with exit code 0 elapsedTime=0.686616 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 21:07:57.551986) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-24 21:07:57.552255) ========= script_repo_revision: 1156db49e976173fc3cf90d2126456fd1e2bae4b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-24 21:07:57.552619) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-24 21:07:57.552882) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-24 21:07:57.562601) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-12-24 21:07:57.562867) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1482640188.252823-602346170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:07:58 INFO - MultiFileLogger online at 20161224 21:07:58 in /builds/slave/test 21:07:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:07:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:07:58 INFO - {'append_to_log': False, 21:07:58 INFO - 'base_work_dir': '/builds/slave/test', 21:07:58 INFO - 'blob_upload_branch': 'Firefox', 21:07:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:07:58 INFO - 'branch': 'Firefox', 21:07:58 INFO - 'buildbot_json_path': 'buildprops.json', 21:07:58 INFO - 'config_files': ('talos/linux_config.py',), 21:07:58 INFO - 'default_actions': ('clobber', 21:07:58 INFO - 'read-buildbot-config', 21:07:58 INFO - 'download-and-extract', 21:07:58 INFO - 'populate-webroot', 21:07:58 INFO - 'create-virtualenv', 21:07:58 INFO - 'install', 21:07:58 INFO - 'run-tests'), 21:07:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:07:58 INFO - 'download_minidump_stackwalk': True, 21:07:58 INFO - 'download_symbols': 'ondemand', 21:07:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:07:58 INFO - 'tooltool.py': '/tools/tooltool.py', 21:07:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:07:58 INFO - '/tools/misc-python/virtualenv.py')}, 21:07:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:07:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:07:58 INFO - 'installer_path': 'installer.exe', 21:07:58 INFO - 'log_level': 'info', 21:07:58 INFO - 'log_name': 'talos', 21:07:58 INFO - 'log_to_console': True, 21:07:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:07:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:07:58 INFO - 'opt_config_files': (), 21:07:58 INFO - 'pip_index': False, 21:07:58 INFO - 'sps_profile': False, 21:07:58 INFO - 'sps_profile_interval': 0, 21:07:58 INFO - 'suite': 'dromaeojs', 21:07:58 INFO - 'system_bits': '32', 21:07:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:07:58 INFO - 'title': 'talos-linux64-ix-027', 21:07:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:07:58 INFO - 'use_talos_json': True, 21:07:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:07:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:07:58 INFO - 'work_dir': 'build'} 21:07:58 INFO - [mozharness: 2016-12-25 05:07:58.204984Z] Running clobber step. 21:07:58 INFO - Running pre-action listener: _resource_record_pre_action 21:07:58 INFO - Running main action method: clobber 21:07:58 INFO - rmtree: /builds/slave/test/build 21:07:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:07:58 INFO - Running post-action listener: _resource_record_post_action 21:07:58 INFO - [mozharness: 2016-12-25 05:07:58.666074Z] Finished clobber step (success) 21:07:58 INFO - [mozharness: 2016-12-25 05:07:58.666177Z] Running read-buildbot-config step. 21:07:58 INFO - Running pre-action listener: _resource_record_pre_action 21:07:58 INFO - Running main action method: read_buildbot_config 21:07:58 INFO - Using buildbot properties: 21:07:58 INFO - { 21:07:58 INFO - "project": "", 21:07:58 INFO - "product": "firefox", 21:07:58 INFO - "script_repo_revision": "production", 21:07:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:07:58 INFO - "repository": "", 21:07:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 21:07:58 INFO - "buildid": "20161224193118", 21:07:58 INFO - "pgo_build": "True", 21:07:58 INFO - "basedir": "/builds/slave/test", 21:07:58 INFO - "buildnumber": 131, 21:07:58 INFO - "slavename": "talos-linux64-ix-027", 21:07:58 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:07:58 INFO - "platform": "ubuntu64_hw", 21:07:58 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:07:58 INFO - "branch": "mozilla-central", 21:07:58 INFO - "repo_path": "mozilla-central", 21:07:58 INFO - "revision": "1156db49e976173fc3cf90d2126456fd1e2bae4b", 21:07:58 INFO - "stage_platform": "linux64-pgo", 21:07:58 INFO - "builduid": "685d4dc036184713b1b435aa061e3f3e", 21:07:58 INFO - "slavebuilddir": "test" 21:07:58 INFO - } 21:07:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 21:07:58 INFO - Running post-action listener: _resource_record_post_action 21:07:58 INFO - [mozharness: 2016-12-25 05:07:58.668629Z] Finished read-buildbot-config step (success) 21:07:58 INFO - [mozharness: 2016-12-25 05:07:58.668719Z] Running download-and-extract step. 21:07:58 INFO - Running pre-action listener: _resource_record_pre_action 21:07:58 INFO - Running main action method: download_and_extract 21:07:58 INFO - mkdir: /builds/slave/test/build/tests 21:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:58 INFO - trying https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:07:58 INFO - Downloading https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/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 21:07:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/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 21:07:59 INFO - Downloaded 1361 bytes. 21:07:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:07:59 INFO - Using the following test package requirements: 21:07:59 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:07:59 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:07:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:07:59 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:07:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:07:59 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:07:59 INFO - u'jsshell-linux-x86_64.zip'], 21:07:59 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:07:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:07:59 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:07:59 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:07:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:07:59 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:07:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:07:59 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:07:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:07:59 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:07:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:07:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:07:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:07:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:07:59 INFO - Fetch https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:08:00 INFO - Expected file size: 21831696 21:08:00 INFO - Obtained file size: 21831696 21:08:04 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 21:08:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:08:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:08:04 INFO - Fetch https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:08:04 INFO - Expected file size: 21831696 21:08:04 INFO - Obtained file size: 21831696 21:08:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 21:08:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:08:09 INFO - Fetch https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:08:10 INFO - Expected file size: 14409776 21:08:10 INFO - Obtained file size: 14409776 21:08:10 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')]} 21:08:10 INFO - trying https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:08:10 INFO - Downloading https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/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 21:08:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/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 21:08:11 INFO - Downloaded 61358762 bytes. 21:08:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:08:11 INFO - mkdir: /builds/slave/test/properties 21:08:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:08:11 INFO - Writing to file /builds/slave/test/properties/build_url 21:08:11 INFO - Contents: 21:08:11 INFO - build_url:https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:08:12 INFO - Running post-action listener: _resource_record_post_action 21:08:12 INFO - Running post-action listener: set_extra_try_arguments 21:08:12 INFO - [mozharness: 2016-12-25 05:08:12.487093Z] Finished download-and-extract step (success) 21:08:12 INFO - [mozharness: 2016-12-25 05:08:12.487394Z] Running populate-webroot step. 21:08:12 INFO - Running pre-action listener: _resource_record_pre_action 21:08:12 INFO - Running main action method: populate_webroot 21:08:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:08:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:08:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:08:12 INFO - '--tppagecycles', 21:08:12 INFO - '7'], 21:08:12 INFO - 'tests': ['tsvgm']}}, 21:08:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:08:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:08:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:08:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:08:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:08:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:08:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:08:12 INFO - 'talos_options': ['--disable-e10s'], 21:08:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:08:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:08:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:08:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:08:12 INFO - 'talos_options': ['--disable-e10s'], 21:08:12 INFO - 'tests': ['damp', 'tps']}, 21:08:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:08:12 INFO - 'tests': ['damp', 'tps']}, 21:08:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:08:12 INFO - 'tests': ['dromaeo_dom']}, 21:08:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:08:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:08:12 INFO - 'tests': ['basic_compositor_video']}, 21:08:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:08:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:08:12 INFO - 'tests': ['a11yr', 21:08:12 INFO - 'ts_paint', 21:08:12 INFO - 'tpaint', 21:08:12 INFO - 'sessionrestore', 21:08:12 INFO - 'sessionrestore_no_auto_restore', 21:08:12 INFO - 'tabpaint']}, 21:08:12 INFO - 'other-e10s': {'tests': ['a11yr', 21:08:12 INFO - 'ts_paint', 21:08:12 INFO - 'tpaint', 21:08:12 INFO - 'sessionrestore', 21:08:12 INFO - 'sessionrestore_no_auto_restore', 21:08:12 INFO - 'tabpaint']}, 21:08:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:08:12 INFO - 'ts_paint', 21:08:12 INFO - 'tpaint', 21:08:12 INFO - 'sessionrestore', 21:08:12 INFO - 'sessionrestore_no_auto_restore', 21:08:12 INFO - 'tabpaint']}, 21:08:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:08:12 INFO - 'ts_paint', 21:08:12 INFO - 'tpaint', 21:08:12 INFO - 'sessionrestore', 21:08:12 INFO - 'sessionrestore_no_auto_restore', 21:08:12 INFO - 'tabpaint']}, 21:08:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:08:12 INFO - 'tests': ['a11yr', 21:08:12 INFO - 'ts_paint', 21:08:12 INFO - 'tpaint', 21:08:12 INFO - 'sessionrestore', 21:08:12 INFO - 'sessionrestore_no_auto_restore', 21:08:12 INFO - 'tabpaint']}, 21:08:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:08:12 INFO - 'tests': ['a11yr', 21:08:12 INFO - 'ts_paint', 21:08:12 INFO - 'tpaint', 21:08:12 INFO - 'sessionrestore', 21:08:12 INFO - 'sessionrestore_no_auto_restore', 21:08:12 INFO - 'tabpaint']}, 21:08:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:08:12 INFO - 'tests': ['tsvgx', 21:08:12 INFO - 'tsvgr_opacity', 21:08:12 INFO - 'tart', 21:08:12 INFO - 'tscrollx', 21:08:12 INFO - 'cart', 21:08:12 INFO - 'tsvg_static']}, 21:08:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:08:12 INFO - 'tsvgr_opacity', 21:08:12 INFO - 'tart', 21:08:12 INFO - 'tscrollx', 21:08:12 INFO - 'cart', 21:08:12 INFO - 'tsvg_static']}, 21:08:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:08:12 INFO - 'talos_options': ['--disable-e10s'], 21:08:12 INFO - 'tests': ['tp5o']}, 21:08:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:08:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:08:12 INFO - 'talos_options': ['--disable-e10s', 21:08:12 INFO - '--xperf_path', 21:08:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:08:12 INFO - 'tests': ['tp5n']}, 21:08:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:08:12 INFO - 'talos_options': ['--xperf_path', 21:08:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:08:12 INFO - 'tests': ['tp5n']}}, 21:08:12 INFO - 'talos.zip': {'path': '', 21:08:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:08:12 INFO - Running post-action listener: _resource_record_post_action 21:08:12 INFO - [mozharness: 2016-12-25 05:08:12.505872Z] Finished populate-webroot step (success) 21:08:12 INFO - [mozharness: 2016-12-25 05:08:12.505965Z] Running create-virtualenv step. 21:08:12 INFO - Running pre-action listener: _resource_record_pre_action 21:08:12 INFO - Running main action method: create_virtualenv 21:08:12 INFO - Creating virtualenv /builds/slave/test/build/venv 21:08:12 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 21:08:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:08:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:08:12 INFO - Using real prefix '/usr' 21:08:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:08:13 INFO - Installing distribute.............................................................................................................................................................................................done. 21:08:15 INFO - Installing pip.................done. 21:08:15 INFO - Return code: 0 21:08:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:08:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:08:15 INFO - Reading from file tmpfile_stdout 21:08:15 INFO - Output received: 21:08:15 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) 21:08:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:15 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1764c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17623f0>, '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 0x1762cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17631a0>, '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-1482640188.252823-602346170', '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 21:08:15 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 21:08:15 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 21:08:15 INFO - Using env: {'DISPLAY': ':0', 21:08:15 INFO - 'HOME': '/home/cltbld', 21:08:15 INFO - 'LANG': 'en_US.UTF-8', 21:08:15 INFO - 'LANGUAGE': 'en_US:en', 21:08:15 INFO - 'LOGNAME': 'cltbld', 21:08:15 INFO - 'MAIL': '/var/mail/cltbld', 21:08:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:15 INFO - 'MOZ_NO_REMOTE': '1', 21:08:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:15 INFO - 'NO_EM_RESTART': '1', 21:08:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:15 INFO - 'PWD': '/builds/slave/test', 21:08:15 INFO - 'SHELL': '/bin/bash', 21:08:15 INFO - 'SHLVL': '1', 21:08:15 INFO - 'TERM': 'linux', 21:08:15 INFO - 'TMOUT': '86400', 21:08:15 INFO - 'USER': 'cltbld', 21:08:15 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482640188.252823-602346170', 21:08:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:15 INFO - '_': '/tools/buildbot/bin/python'} 21:08:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:15 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 21:08:15 INFO - Cleaning up... 21:08:15 INFO - Return code: 0 21:08:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:15 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1764c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17623f0>, '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 0x1762cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17631a0>, '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-1482640188.252823-602346170', '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 21:08:15 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 21:08:15 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 21:08:15 INFO - Using env: {'DISPLAY': ':0', 21:08:15 INFO - 'HOME': '/home/cltbld', 21:08:15 INFO - 'LANG': 'en_US.UTF-8', 21:08:15 INFO - 'LANGUAGE': 'en_US:en', 21:08:15 INFO - 'LOGNAME': 'cltbld', 21:08:15 INFO - 'MAIL': '/var/mail/cltbld', 21:08:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:15 INFO - 'MOZ_NO_REMOTE': '1', 21:08:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:15 INFO - 'NO_EM_RESTART': '1', 21:08:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:15 INFO - 'PWD': '/builds/slave/test', 21:08:15 INFO - 'SHELL': '/bin/bash', 21:08:15 INFO - 'SHLVL': '1', 21:08:15 INFO - 'TERM': 'linux', 21:08:15 INFO - 'TMOUT': '86400', 21:08:15 INFO - 'USER': 'cltbld', 21:08:15 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482640188.252823-602346170', 21:08:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:15 INFO - '_': '/tools/buildbot/bin/python'} 21:08:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:15 INFO - Downloading/unpacking psutil>=3.1.1 21:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:08:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:08:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:08:17 INFO - Installing collected packages: psutil 21:08:17 INFO - Running setup.py install for psutil 21:08:17 INFO - building 'psutil._psutil_linux' extension 21:08:17 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 21:08:18 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 21:08:18 INFO - building 'psutil._psutil_posix' extension 21:08:18 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 21:08:18 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 21:08:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:08:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:08:18 INFO - Successfully installed psutil 21:08:18 INFO - Cleaning up... 21:08:18 INFO - Return code: 0 21:08:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:08:18 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')]} 21:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:18 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')]} 21:08:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:18 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1764c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17623f0>, '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 0x1762cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17631a0>, '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-1482640188.252823-602346170', '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 21:08:18 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 21:08:18 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 21:08:18 INFO - Using env: {'DISPLAY': ':0', 21:08:18 INFO - 'HOME': '/home/cltbld', 21:08:18 INFO - 'LANG': 'en_US.UTF-8', 21:08:18 INFO - 'LANGUAGE': 'en_US:en', 21:08:18 INFO - 'LOGNAME': 'cltbld', 21:08:18 INFO - 'MAIL': '/var/mail/cltbld', 21:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:18 INFO - 'MOZ_NO_REMOTE': '1', 21:08:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:18 INFO - 'NO_EM_RESTART': '1', 21:08:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:18 INFO - 'PWD': '/builds/slave/test', 21:08:18 INFO - 'SHELL': '/bin/bash', 21:08:18 INFO - 'SHLVL': '1', 21:08:18 INFO - 'TERM': 'linux', 21:08:18 INFO - 'TMOUT': '86400', 21:08:18 INFO - 'USER': 'cltbld', 21:08:18 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482640188.252823-602346170', 21:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:18 INFO - '_': '/tools/buildbot/bin/python'} 21:08:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:08:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:08:20 INFO - Installing collected packages: mozsystemmonitor 21:08:20 INFO - Running setup.py install for mozsystemmonitor 21:08:20 INFO - Successfully installed mozsystemmonitor 21:08:20 INFO - Cleaning up... 21:08:20 INFO - Return code: 0 21:08:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:08:20 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')]} 21:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:20 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')]} 21:08:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:20 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1764c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17623f0>, '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 0x1762cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17631a0>, '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-1482640188.252823-602346170', '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 21:08:20 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 21:08:20 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 21:08:20 INFO - Using env: {'DISPLAY': ':0', 21:08:20 INFO - 'HOME': '/home/cltbld', 21:08:20 INFO - 'LANG': 'en_US.UTF-8', 21:08:20 INFO - 'LANGUAGE': 'en_US:en', 21:08:20 INFO - 'LOGNAME': 'cltbld', 21:08:20 INFO - 'MAIL': '/var/mail/cltbld', 21:08:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:20 INFO - 'MOZ_NO_REMOTE': '1', 21:08:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:20 INFO - 'NO_EM_RESTART': '1', 21:08:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:20 INFO - 'PWD': '/builds/slave/test', 21:08:20 INFO - 'SHELL': '/bin/bash', 21:08:20 INFO - 'SHLVL': '1', 21:08:20 INFO - 'TERM': 'linux', 21:08:20 INFO - 'TMOUT': '86400', 21:08:20 INFO - 'USER': 'cltbld', 21:08:20 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482640188.252823-602346170', 21:08:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:20 INFO - '_': '/tools/buildbot/bin/python'} 21:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:21 INFO - Downloading/unpacking jsonschema==2.5.1 21:08:21 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 21:08:21 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 21:08:21 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 21:08:21 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 21:08:21 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 21:08:21 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 21:08:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:08:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:08:23 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 21:08:23 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 21:08:23 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 21:08:23 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 21:08:23 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 21:08:23 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 21:08:23 INFO - Downloading functools32-3.2.3-2.tar.gz 21:08:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:08:23 INFO - warning: no files found matching '*.txt' 21:08:23 INFO - no previously-included directories found matching 'build' 21:08:23 INFO - no previously-included directories found matching 'dist' 21:08:23 INFO - no previously-included directories found matching '.git*' 21:08:23 INFO - Installing collected packages: jsonschema, functools32 21:08:23 INFO - Running setup.py install for functools32 21:08:23 INFO - warning: no files found matching '*.txt' 21:08:23 INFO - no previously-included directories found matching 'build' 21:08:23 INFO - no previously-included directories found matching 'dist' 21:08:23 INFO - no previously-included directories found matching '.git*' 21:08:23 INFO - Successfully installed jsonschema functools32 21:08:23 INFO - Cleaning up... 21:08:23 INFO - Return code: 0 21:08:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:23 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1764c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17623f0>, '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 0x1762cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17631a0>, '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-1482640188.252823-602346170', '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 21:08:23 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 21:08:23 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 21:08:23 INFO - Using env: {'DISPLAY': ':0', 21:08:23 INFO - 'HOME': '/home/cltbld', 21:08:23 INFO - 'LANG': 'en_US.UTF-8', 21:08:23 INFO - 'LANGUAGE': 'en_US:en', 21:08:23 INFO - 'LOGNAME': 'cltbld', 21:08:23 INFO - 'MAIL': '/var/mail/cltbld', 21:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:23 INFO - 'MOZ_NO_REMOTE': '1', 21:08:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:23 INFO - 'NO_EM_RESTART': '1', 21:08:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:23 INFO - 'PWD': '/builds/slave/test', 21:08:23 INFO - 'SHELL': '/bin/bash', 21:08:23 INFO - 'SHLVL': '1', 21:08:23 INFO - 'TERM': 'linux', 21:08:23 INFO - 'TMOUT': '86400', 21:08:23 INFO - 'USER': 'cltbld', 21:08:23 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482640188.252823-602346170', 21:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:23 INFO - '_': '/tools/buildbot/bin/python'} 21:08:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:08:23 INFO - Cleaning up... 21:08:23 INFO - Return code: 0 21:08:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:23 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1764c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17623f0>, '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 0x1762cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17631a0>, '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-1482640188.252823-602346170', '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 21:08:23 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 21:08:23 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 21:08:23 INFO - Using env: {'DISPLAY': ':0', 21:08:23 INFO - 'HOME': '/home/cltbld', 21:08:23 INFO - 'LANG': 'en_US.UTF-8', 21:08:23 INFO - 'LANGUAGE': 'en_US:en', 21:08:23 INFO - 'LOGNAME': 'cltbld', 21:08:23 INFO - 'MAIL': '/var/mail/cltbld', 21:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:23 INFO - 'MOZ_NO_REMOTE': '1', 21:08:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:23 INFO - 'NO_EM_RESTART': '1', 21:08:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:23 INFO - 'PWD': '/builds/slave/test', 21:08:23 INFO - 'SHELL': '/bin/bash', 21:08:23 INFO - 'SHLVL': '1', 21:08:23 INFO - 'TERM': 'linux', 21:08:23 INFO - 'TMOUT': '86400', 21:08:23 INFO - 'USER': 'cltbld', 21:08:23 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482640188.252823-602346170', 21:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:23 INFO - '_': '/tools/buildbot/bin/python'} 21:08:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:23 INFO - Downloading/unpacking blobuploader==1.2.4 21:08:23 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 21:08:23 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 21:08:23 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 21:08:23 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 21:08:23 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 21:08:23 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 21:08:25 INFO - Downloading blobuploader-1.2.4.tar.gz 21:08:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:08:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:08:26 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 21:08:26 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 21:08:26 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 21:08:26 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 21:08:26 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 21:08:26 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 21:08:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:08:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:08:26 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 21:08:26 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 21:08:26 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 21:08:26 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 21:08:26 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 21:08:26 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 21:08:26 INFO - Downloading docopt-0.6.1.tar.gz 21:08:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:08:26 INFO - Installing collected packages: blobuploader, requests, docopt 21:08:26 INFO - Running setup.py install for blobuploader 21:08:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:08:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:08:26 INFO - Running setup.py install for requests 21:08:27 INFO - Running setup.py install for docopt 21:08:27 INFO - Successfully installed blobuploader requests docopt 21:08:27 INFO - Cleaning up... 21:08:27 INFO - Return code: 0 21:08:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:27 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1764c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17623f0>, '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 0x1762cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17631a0>, '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-1482640188.252823-602346170', '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 21:08:27 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 21:08:27 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 21:08:27 INFO - Using env: {'DISPLAY': ':0', 21:08:27 INFO - 'HOME': '/home/cltbld', 21:08:27 INFO - 'LANG': 'en_US.UTF-8', 21:08:27 INFO - 'LANGUAGE': 'en_US:en', 21:08:27 INFO - 'LOGNAME': 'cltbld', 21:08:27 INFO - 'MAIL': '/var/mail/cltbld', 21:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:27 INFO - 'MOZ_NO_REMOTE': '1', 21:08:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:27 INFO - 'NO_EM_RESTART': '1', 21:08:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:27 INFO - 'PWD': '/builds/slave/test', 21:08:27 INFO - 'SHELL': '/bin/bash', 21:08:27 INFO - 'SHLVL': '1', 21:08:27 INFO - 'TERM': 'linux', 21:08:27 INFO - 'TMOUT': '86400', 21:08:27 INFO - 'USER': 'cltbld', 21:08:27 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482640188.252823-602346170', 21:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:27 INFO - '_': '/tools/buildbot/bin/python'} 21:08:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:08:27 INFO - Running setup.py (path:/tmp/pip-o6hn1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:08:27 INFO - Running setup.py (path:/tmp/pip-N1twwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:08:27 INFO - Running setup.py (path:/tmp/pip-vRXNx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:08:27 INFO - Running setup.py (path:/tmp/pip-ew56xS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:08:27 INFO - Running setup.py (path:/tmp/pip-5XNzUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:08:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:08:27 INFO - Running setup.py (path:/tmp/pip-f1iGgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:08:28 INFO - Running setup.py (path:/tmp/pip-UYgveu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:08:28 INFO - Running setup.py (path:/tmp/pip-Q7QRDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:08:28 INFO - Running setup.py (path:/tmp/pip-zvytus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:08:28 INFO - Running setup.py (path:/tmp/pip-BzU3mP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:08:28 INFO - Running setup.py (path:/tmp/pip-gYyusY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:08:28 INFO - Running setup.py (path:/tmp/pip-5HaFo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:08:28 INFO - Running setup.py (path:/tmp/pip-qmRGkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:08:28 INFO - Running setup.py (path:/tmp/pip-VAXMRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:28 INFO - Running setup.py (path:/tmp/pip-CjyktN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:08:28 INFO - Running setup.py (path:/tmp/pip-OQI0It-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:08:28 INFO - Running setup.py (path:/tmp/pip-1JPB0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:08:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:08:28 INFO - Running setup.py install for manifestparser 21:08:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:08:28 INFO - Running setup.py install for mozcrash 21:08:29 INFO - Running setup.py install for mozdebug 21:08:29 INFO - Running setup.py install for mozdevice 21:08:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:08:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:08:29 INFO - Running setup.py install for mozfile 21:08:29 INFO - Running setup.py install for mozhttpd 21:08:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:08:29 INFO - Running setup.py install for mozinfo 21:08:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:08:29 INFO - Running setup.py install for mozInstall 21:08:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:08:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:08:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:08:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:08:29 INFO - Running setup.py install for mozleak 21:08:29 INFO - Running setup.py install for mozlog 21:08:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:08:29 INFO - Running setup.py install for moznetwork 21:08:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:08:29 INFO - Running setup.py install for mozprocess 21:08:30 INFO - Running setup.py install for mozprofile 21:08:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:08:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:08:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:08:30 INFO - Running setup.py install for mozrunner 21:08:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:08:30 INFO - Running setup.py install for mozscreenshot 21:08:30 INFO - Running setup.py install for moztest 21:08:30 INFO - Running setup.py install for mozversion 21:08:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:08:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:08:30 INFO - Cleaning up... 21:08:30 INFO - Return code: 0 21:08:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:08:30 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')]} 21:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:30 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')]} 21:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:30 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1764c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17623f0>, '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 0x1762cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17631a0>, '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-1482640188.252823-602346170', '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 21:08:30 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 21:08:30 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 21:08:30 INFO - Using env: {'DISPLAY': ':0', 21:08:30 INFO - 'HOME': '/home/cltbld', 21:08:30 INFO - 'LANG': 'en_US.UTF-8', 21:08:30 INFO - 'LANGUAGE': 'en_US:en', 21:08:30 INFO - 'LOGNAME': 'cltbld', 21:08:30 INFO - 'MAIL': '/var/mail/cltbld', 21:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:30 INFO - 'MOZ_NO_REMOTE': '1', 21:08:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:30 INFO - 'NO_EM_RESTART': '1', 21:08:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:30 INFO - 'PWD': '/builds/slave/test', 21:08:30 INFO - 'SHELL': '/bin/bash', 21:08:30 INFO - 'SHLVL': '1', 21:08:30 INFO - 'TERM': 'linux', 21:08:30 INFO - 'TMOUT': '86400', 21:08:30 INFO - 'USER': 'cltbld', 21:08:30 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482640188.252823-602346170', 21:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:30 INFO - '_': '/tools/buildbot/bin/python'} 21:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:08:30 INFO - Running setup.py (path:/tmp/pip-FV0EOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:08:30 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)) 21:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:08:30 INFO - Running setup.py (path:/tmp/pip-ePfQeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:08:31 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)) 21:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:08:31 INFO - Running setup.py (path:/tmp/pip-yIVvxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:08:31 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)) 21:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:08:31 INFO - Running setup.py (path:/tmp/pip-a_O3BX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:08:31 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)) 21:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:08:31 INFO - Running setup.py (path:/tmp/pip-UcWKdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:08:31 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)) 21:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:08:31 INFO - Running setup.py (path:/tmp/pip-nR7fvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:08:31 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)) 21:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:08:31 INFO - Running setup.py (path:/tmp/pip-f854R_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:08:31 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)) 21:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:08:31 INFO - Running setup.py (path:/tmp/pip-DTngZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:08:31 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)) 21:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:08:31 INFO - Running setup.py (path:/tmp/pip-vMyNkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:08:31 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)) 21:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:08:31 INFO - Running setup.py (path:/tmp/pip-PPQEF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:08:31 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)) 21:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:08:31 INFO - Running setup.py (path:/tmp/pip-0ObQ_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:08:31 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)) 21:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:08:31 INFO - Running setup.py (path:/tmp/pip-aCF5_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:08:31 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)) 21:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:08:31 INFO - Running setup.py (path:/tmp/pip-GXOPmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:08:31 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)) 21:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:08:31 INFO - Running setup.py (path:/tmp/pip-kCpzf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:08:31 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)) 21:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:31 INFO - Running setup.py (path:/tmp/pip-GKGUtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:31 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)) 21:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:08:31 INFO - Running setup.py (path:/tmp/pip-fbyj30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:08:32 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)) 21:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:08:32 INFO - Running setup.py (path:/tmp/pip-RuQFv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:08:32 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)) 21:08:32 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)) 21:08:32 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)) 21:08:32 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)) 21:08:32 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)) 21:08:32 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)) 21:08:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:08:32 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 21:08:32 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 21:08:32 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 21:08:32 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 21:08:32 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 21:08:32 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 21:08:34 INFO - Downloading blessings-1.6.tar.gz 21:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:08:34 INFO - Installing collected packages: blessings 21:08:34 INFO - Running setup.py install for blessings 21:08:34 INFO - Successfully installed blessings 21:08:34 INFO - Cleaning up... 21:08:34 INFO - Return code: 0 21:08:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:08:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:08:34 INFO - Reading from file tmpfile_stdout 21:08:34 INFO - Current package versions: 21:08:34 INFO - argparse == 1.2.1 21:08:34 INFO - blessings == 1.6 21:08:34 INFO - blobuploader == 1.2.4 21:08:34 INFO - docopt == 0.6.1 21:08:34 INFO - functools32 == 3.2.3-2 21:08:34 INFO - jsonschema == 2.5.1 21:08:34 INFO - manifestparser == 1.1 21:08:34 INFO - mozInstall == 1.12 21:08:34 INFO - mozcrash == 1.0 21:08:34 INFO - mozdebug == 0.1 21:08:34 INFO - mozdevice == 0.49 21:08:34 INFO - mozfile == 1.2 21:08:34 INFO - mozhttpd == 0.7 21:08:34 INFO - mozinfo == 0.9 21:08:34 INFO - mozleak == 0.1 21:08:34 INFO - mozlog == 3.4 21:08:34 INFO - moznetwork == 0.27 21:08:34 INFO - mozprocess == 0.23 21:08:34 INFO - mozprofile == 0.28 21:08:34 INFO - mozrunner == 6.13 21:08:34 INFO - mozscreenshot == 0.1 21:08:34 INFO - mozsystemmonitor == 0.3 21:08:34 INFO - moztest == 0.7 21:08:34 INFO - mozversion == 1.4 21:08:34 INFO - psutil == 3.1.1 21:08:34 INFO - requests == 1.2.3 21:08:34 INFO - wsgiref == 0.1.2 21:08:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:08:34 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')]} 21:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:34 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')]} 21:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:34 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1764c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17623f0>, '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 0x1762cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17631a0>, '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-1482640188.252823-602346170', '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 21:08:34 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 21:08:34 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 21:08:34 INFO - Using env: {'DISPLAY': ':0', 21:08:34 INFO - 'HOME': '/home/cltbld', 21:08:34 INFO - 'LANG': 'en_US.UTF-8', 21:08:34 INFO - 'LANGUAGE': 'en_US:en', 21:08:34 INFO - 'LOGNAME': 'cltbld', 21:08:34 INFO - 'MAIL': '/var/mail/cltbld', 21:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:34 INFO - 'MOZ_NO_REMOTE': '1', 21:08:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:34 INFO - 'NO_EM_RESTART': '1', 21:08:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:34 INFO - 'PWD': '/builds/slave/test', 21:08:34 INFO - 'SHELL': '/bin/bash', 21:08:34 INFO - 'SHLVL': '1', 21:08:34 INFO - 'TERM': 'linux', 21:08:34 INFO - 'TMOUT': '86400', 21:08:34 INFO - 'USER': 'cltbld', 21:08:34 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482640188.252823-602346170', 21:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:34 INFO - '_': '/tools/buildbot/bin/python'} 21:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:34 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)) 21:08:34 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)) 21:08:34 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)) 21:08:34 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)) 21:08:34 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)) 21:08:34 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)) 21:08:34 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)) 21:08:34 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)) 21:08:34 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)) 21:08:34 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)) 21:08:34 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)) 21:08:34 INFO - Cleaning up... 21:08:34 INFO - Return code: 0 21:08:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:08:34 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')]} 21:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:34 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')]} 21:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:34 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1764c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17623f0>, '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 0x1762cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17631a0>, '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-1482640188.252823-602346170', '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 21:08:34 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 21:08:34 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 21:08:34 INFO - Using env: {'DISPLAY': ':0', 21:08:34 INFO - 'HOME': '/home/cltbld', 21:08:34 INFO - 'LANG': 'en_US.UTF-8', 21:08:34 INFO - 'LANGUAGE': 'en_US:en', 21:08:34 INFO - 'LOGNAME': 'cltbld', 21:08:34 INFO - 'MAIL': '/var/mail/cltbld', 21:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:34 INFO - 'MOZ_NO_REMOTE': '1', 21:08:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:34 INFO - 'NO_EM_RESTART': '1', 21:08:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:34 INFO - 'PWD': '/builds/slave/test', 21:08:34 INFO - 'SHELL': '/bin/bash', 21:08:34 INFO - 'SHLVL': '1', 21:08:34 INFO - 'TERM': 'linux', 21:08:34 INFO - 'TMOUT': '86400', 21:08:34 INFO - 'USER': 'cltbld', 21:08:34 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482640188.252823-602346170', 21:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:34 INFO - '_': '/tools/buildbot/bin/python'} 21:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:08:35 INFO - Cleaning up... 21:08:35 INFO - Return code: 0 21:08:35 INFO - Running post-action listener: _resource_record_post_action 21:08:35 INFO - Running post-action listener: _start_resource_monitoring 21:08:35 INFO - Starting resource monitoring. 21:08:35 INFO - [mozharness: 2016-12-25 05:08:35.056032Z] Finished create-virtualenv step (success) 21:08:35 INFO - [mozharness: 2016-12-25 05:08:35.056553Z] Running install step. 21:08:35 INFO - Running pre-action listener: _resource_record_pre_action 21:08:35 INFO - Running main action method: install 21:08:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:08:35 INFO - Reading from file tmpfile_stdout 21:08:35 INFO - Detecting whether we're running mozinstall >=1.0... 21:08:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:08:35 INFO - Reading from file tmpfile_stdout 21:08:35 INFO - Output received: 21:08:35 INFO - Usage: mozinstall [options] installer 21:08:35 INFO - Options: 21:08:35 INFO - -h, --help show this help message and exit 21:08:35 INFO - -d DEST, --destination=DEST 21:08:35 INFO - Directory to install application into. [default: 21:08:35 INFO - "/builds/slave/test"] 21:08:35 INFO - --app=APP Application being installed. [default: firefox] 21:08:35 INFO - mkdir: /builds/slave/test/build/application 21:08:35 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'] 21:08:35 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 21:08:52 INFO - Reading from file tmpfile_stdout 21:08:52 INFO - Output received: 21:08:52 INFO - /builds/slave/test/build/application/firefox/firefox 21:08:52 INFO - Running post-action listener: _resource_record_post_action 21:08:52 INFO - [mozharness: 2016-12-25 05:08:52.681372Z] Finished install step (success) 21:08:52 INFO - [mozharness: 2016-12-25 05:08:52.681623Z] Running run-tests step. 21:08:52 INFO - Running pre-action listener: _resource_record_pre_action 21:08:52 INFO - Running main action method: run_tests 21:08:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:08:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:08:52 INFO - Python 2.7.3 21:08:52 INFO - Return code: 0 21:08:52 INFO - grabbing minidump binary from tooltool 21:08:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:52 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 0x17623f0>, '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 0x1762cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17631a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:08:52 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 21:08:52 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 21:08:52 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 21:08:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:08:52 INFO - Return code: 0 21:08:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:08:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:08:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:08:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:08:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:08:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/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 21:08:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/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 21:08:52 INFO - Using env: {'DISPLAY': ':0', 21:08:52 INFO - 'HOME': '/home/cltbld', 21:08:52 INFO - 'LANG': 'en_US.UTF-8', 21:08:52 INFO - 'LANGUAGE': 'en_US:en', 21:08:52 INFO - 'LOGNAME': 'cltbld', 21:08:52 INFO - 'MAIL': '/var/mail/cltbld', 21:08:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:08:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:08:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:52 INFO - 'MOZ_NO_REMOTE': '1', 21:08:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:08:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:52 INFO - 'NO_EM_RESTART': '1', 21:08:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:52 INFO - 'PWD': '/builds/slave/test', 21:08:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:08:52 INFO - 'SHELL': '/bin/bash', 21:08:52 INFO - 'SHLVL': '1', 21:08:52 INFO - 'TERM': 'linux', 21:08:52 INFO - 'TMOUT': '86400', 21:08:52 INFO - 'USER': 'cltbld', 21:08:52 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1482640188.252823-602346170', 21:08:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:52 INFO - '_': '/tools/buildbot/bin/python'} 21:08:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/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 21:08:52 INFO - mozversion application_buildid: 20161224193118 21:08:52 INFO - mozversion application_changeset: 1156db49e976173fc3cf90d2126456fd1e2bae4b 21:08:52 INFO - mozversion application_display_name: Nightly 21:08:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:08:52 INFO - mozversion application_name: Firefox 21:08:52 INFO - mozversion application_remotingname: firefox 21:08:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:08:52 INFO - mozversion application_vendor: Mozilla 21:08:52 INFO - mozversion application_version: 53.0a1 21:08:52 INFO - mozversion platform_buildid: 20161224193118 21:08:52 INFO - mozversion platform_changeset: 1156db49e976173fc3cf90d2126456fd1e2bae4b 21:08:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:08:52 INFO - mozversion platform_version: 53.0a1 21:08:52 INFO - using testdate: 1482642532 21:08:52 INFO - actual date: 1482642532 21:08:52 INFO - starting webserver on 'localhost:44786' 21:08:52 INFO - SUITE-START | Running 2 tests 21:08:52 INFO - TEST-START | dromaeo_css 21:08:52 INFO - operating with platform_type : linux_ 21:08:52 INFO - Initialising browser for dromaeo_css test... 21:08:52 INFO - TEST-INFO | started process 9526 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD5VBjw/profile http://localhost:44786/getInfo.html) 21:08:53 INFO - PROCESS | 9526 | 1482642533441 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 21:08:56 INFO - PROCESS | 9526 | __metrics Screen width/height:1600/1200 21:08:56 INFO - PROCESS | 9526 | colorDepth:24 21:08:56 INFO - PROCESS | 9526 | Browser inner width/height: 1024/697 21:08:56 INFO - PROCESS | 9526 | __metrics 21:08:56 INFO - PROCESS | 9526 | 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" 21:08:56 INFO - PROCESS | 9526 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:08:57 INFO - PROCESS | 9526 | console.error: PushService: 21:08:57 INFO - PROCESS | 9526 | stateChangeProcessEnqueue: Error transitioning state 21:08:57 INFO - PROCESS | 9526 | UnknownError 21:08:58 INFO - TEST-INFO | 9526: exit 0 21:08:58 INFO - Browser initialized. 21:08:58 INFO - Running cycle 1/1 for dromaeo_css test... 21:08:58 INFO - TEST-INFO | started process 9607 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD5VBjw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:09:10 INFO - PROCESS | 9607 | RSS: Main: 147161088 21:09:10 INFO - PROCESS | 9607 | 21:12:11 INFO - PROCESS | 9607 | Cycle 1(1): loaded http://localhost:44786/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44786/tests/dromaeo/cssquery-ext.html) 21:12:13 INFO - PROCESS | 9607 | RSS: Main: 252600320 21:12:13 INFO - PROCESS | 9607 | 21:13:58 INFO - PROCESS | 9607 | ************************* 21:13:58 INFO - PROCESS | 9607 | A coding exception was thrown and uncaught in a Task. 21:13:58 INFO - PROCESS | 9607 | 21:13:58 INFO - PROCESS | 9607 | Full message: ReferenceError: fetch is not defined 21:13:58 INFO - PROCESS | 9607 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 21:13:58 INFO - PROCESS | 9607 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 21:13:58 INFO - PROCESS | 9607 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 21:13:58 INFO - PROCESS | 9607 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:13:58 INFO - PROCESS | 9607 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:13:58 INFO - PROCESS | 9607 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:13:58 INFO - PROCESS | 9607 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 21:13:58 INFO - PROCESS | 9607 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:13:58 INFO - PROCESS | 9607 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:13:58 INFO - PROCESS | 9607 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:13:58 INFO - PROCESS | 9607 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 21:13:58 INFO - PROCESS | 9607 | 21:13:58 INFO - PROCESS | 9607 | ************************* 21:15:15 INFO - PROCESS | 9607 | Cycle 1(1): loaded http://localhost:44786/tests/dromaeo/cssquery-ext.html (next: http://localhost:44786/tests/dromaeo/cssquery-jquery.html) 21:15:16 INFO - PROCESS | 9607 | RSS: Main: 242077696 21:15:16 INFO - PROCESS | 9607 | 21:18:18 INFO - PROCESS | 9607 | Cycle 1(1): loaded http://localhost:44786/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44786/tests/dromaeo/cssquery-mootools.html) 21:18:18 INFO - PROCESS | 9607 | RSS: Main: 217993216 21:18:18 INFO - PROCESS | 9607 | 21:21:20 INFO - PROCESS | 9607 | Cycle 1(1): loaded http://localhost:44786/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44786/tests/dromaeo/cssquery-prototype.html) 21:21:21 INFO - PROCESS | 9607 | RSS: Main: 235921408 21:21:21 INFO - PROCESS | 9607 | 21:24:24 INFO - PROCESS | 9607 | Cycle 1(1): loaded http://localhost:44786/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44786/tests/dromaeo/cssquery-yui.html) 21:24:24 INFO - PROCESS | 9607 | RSS: Main: 183156736 21:24:24 INFO - PROCESS | 9607 | 21:27:27 INFO - PROCESS | 9607 | Cycle 1(1): loaded http://localhost:44786/tests/dromaeo/cssquery-yui.html (next: http://localhost:44786/tests/dromaeo/cssquery-dojo.html) 21:27:27 INFO - PROCESS | 9607 | RSS: Main: 275300352 21:27:27 INFO - PROCESS | 9607 | 21:27:27 INFO - PROCESS | 9607 | __start_tp_report 21:27:27 INFO - PROCESS | 9607 | _x_x_mozilla_page_load 21:27:27 INFO - PROCESS | 9607 | _x_x_mozilla_page_load_details 21:27:27 INFO - PROCESS | 9607 | |i|pagename|runs| 21:27:27 INFO - PROCESS | 9607 | |0;dojo.html;3151.5272709093633;3183.8408079596015;3196.8081915085095;3209.0533292678656;3219.1791093271213;5296.152615581508;5364.409914909362;5383.057964856149;5386.542143917762;5413.972930135349;5376.301080859493;5380.031594313022;5387.838364849051;5444.292242008543;5455.863603409907;4817.012512434942;4840.6369522285795;4867.3915760529935;4872.366592342991;4913.263010548429;4856.830010949618;4952.430470495885;4959.429665588467;4990.201567749161;5012.248162775594;4757.215059515176;4936.531029552187;4939.975300123477;4952.603791696655;5024.547790698836;4781.760911954423;4826.155422800995;4860.8055677772845;4897.608191344684;4923.532264434873;4879.487653796371;4909.091818013643;4914.975425122887;4918.9754051229875;4926.827561035347;5389.272448219479;5432.266644003049;5440.129579267338;5445.019896418643;5456.863578410532;5514.641548299388;5541.251930989345;5682.971585142089;5712.57155713319;5725.513331366838;5701.714914254312;5729.083346664514;5737.282839645044;5760.855978600526;5773.134029895507;5359;5366.4901834326065;5368.9731551342775;5369.221462887859;5370.516653501165;5785.566082543742;5810.186573879576;5874.488715713522;5881.735321910524;5934.406559344031;5725.51333136688;5727.026405511073;5730.713464326767;5773.769049237983;5793.8551536211935;5793.217915581343;5796.188533605298;5822.155787410802;5850.444207800252;5850.707464626752;5559.249501317351;5565.02612042899;5567.109262517978;5601.467860553241;5744.597878148573;5633.605647604629;5638.323401191801;5650.0959846424375;5695.943040569541;5697;4304.966807966066;4309.095090031066;4311.07311927937;4317.07182955666;4346.847860324937;3836.55879573847;3846.3461211594094;3851.4607954886337;3852.653261206449;3869.6323849234277;5393.892122157535;5430.5927055471;5437.646552974044;5469.261649677323;5516;5742.224799652077;5756.1653560233535;5761.567882408753;5761.711914404263;5770.740316685759;5171.172612382076;5465.098258787335;5466.972665136649;5474.808381706621;5477.123660214346;4765.070811191785;4816.988432429159;4821.421429428491;4825.831095911626;4827.25177597473;6030.939690603213;6045.153678484928;6051.183090282843;6051.213342265565;6051.909221361683;5797.6521408713925;5830.416958304305;5834.329052158886;5841.153032810304;5848.298204215351;5991.400859913973;6021.307549631849;6042.456178943916;6054.243219597551;6058.091286307089;5670.716464176692;5670.773169073191;5672.517835983823;5672.971635141963;5687.146927960674;5758.71206439668;5760.366359700513;5768.913466297925;5769.3942136076785;5773.769049238152;5529.032419326682;5563.248961390323;5570.888582228414;5639.661620302795;5642.576806739593;5596.664200147841;5627.380988091225;5630.859228519156;5634.718264086861;5638.520725738358;5446.809361672323;5484.808031718871;5534.667919924817;5534.91697624544;5601.831945041656;5617.157426386075;5631.2960879890015;5642.5485961122195;5650.378458369658;5677.148427735872;5494.615376923737;5498.780048798003;5506.421825708243;5509.586780991522;5511.834644960658 21:27:27 INFO - PROCESS | 9607 | |1;ext.html;553.8919910617429;593.9931815572605;594.0554518315877;598.9880202395952;601.1463721515447;31557.368852622963;32637;36298.45552316718;41982;43006.78496607512;44746.55253447464;45284.77357613215;45304.546954530444;45331.54668453323;45342.546574534244;8813.25087367573;8980.775480613005;8983;8984.056674049229;8988.730338089868;8485.0666426693;8664.436811607256;8932.8660070099;9040.412373195753;10455.947720261389;10386.948065259701;10715.78568428631;10735.087517561016;10736;10750.139988800876;10764.892351076507;10819.080378167866;10829.83755243672;10867.130629549696;10876;10551.94724026375;10635.46822658864;10643.521041553071;10648.094911932418;10652.680419587425;42732;42992.57007429917;43035.13929721419;43146;43216;4122.484689413824;4171.395147703596;4207.326827707583;4252.213340532012;4263.658907287409;32274.193145171557;32369.51445728316;32637.347253054806;32898.83550582256;32989.6701032989;3956.5647778744315;4071.674266058738;4090.3455447128317;4123.154753275571;4249.893752656178;324009;324625;325905;326437.5171862096;327119;52872;53208;53310.200346994825;53343.73328133412;53345.46654533405;55560.44439555633;55906;55929.720351397984;56014;56033;15578;16175.67648647044;16202.675946481004;16205.432809851598;16205.432809851834;12410.131290809737;12487.063470239587;12496.875031249752;12511.311877846623;12511.687207819878;15295.005824621365;15583.142927139117;16298.348066077462;16461.917690411472;16745.0649097462;6397.61614303134;6640.314625382863;6654.70233304501;6745.494087943423;6883.483738719616;876.6318146378536;880.4629176202511;888.0897080492547;900.1808354397467;905.7101727447154;30334;31570;31634.525482117788;31688.683113169034;32218.516722249184;1043.2436483549518;1047.0681093826502;1050.333038520545;1051.4637534857125;1158.1834806461425;15211.467598633995;16509.752353714703;16641.334346625998;16805.24376403065;16918.74621880673;532301;795449.4323039302;800771;806106;808170;47897.76051119722;49199.26201106986;49330.75334623304;49335.75332123388;49383.50616493861;1342.0806747378078;1353.824002879639;1375.848656647768;1376.7590671632424;1378.8138601288697;27398;28358.85820570884;28755;29140.7085929138;29222.123336299945;44590.10819783651;45206.32190517211;45318.86702832456;45334;45345.77327113343;374394;374731;376332;376466;377822;25691;25858.22425327243;25950.22149335523;26079.478410431304;26095.217143485726;43166;43333.78333108314;43417.56582434135;43481.34777978396;43994;35882;35900.64099358973;36064.81967590145;36157.81921090378;36271.818640906626;34869.476957845145;35469.645303547666;36041.81979090193;36152.63847361493;39593.60406395899;35581.82209088938;36207.27585448329;36542.269154617294;37117.875550533856;37123.25753484861;29950.25124371908;33126.33747325088;33529;33704.494432583044;33879.66120338765;25102;25466.61800072963;25849.741502584733;25868.611970820075;26077.217683469527 21:27:27 INFO - PROCESS | 9607 | |2;jquery.html;376.3864900212653;392.76434139516294;395.3595175815179;404.20170163926247;422.7294531499839;13664.31678416079;15428.614284642876;18536.351227707033;18632.25470981159;19144.13851376688;18129.365472208476;18326.541836454064;18505.629887402243;18556.44330670079;18560.721589176173;5544.140658197975;5637.985162670718;5762.222100016486;5765.1928729977835;5769.278840144982;5500.147477141049;5698.458646428583;5700.144978253254;5701.885962280772;5702.429757024285;5460.31746031746;5691.03252447085;5693.28833895763;5707.714614269291;5708.08670612702;5581.49766521013;5643.6049476536655;5677.943220567783;5681.431856814327;5682.403347648478;4931;5062.11413002723;5114;5118.436971933084;5119.078565858144;21465.46336341588;21502.677459838116;21515.46211344713;21648.78351216499;21827.890860545594;2789.888404463819;2806.845623490707;2811.550151975674;2821.5908693239367;2837.858107094637;13265.734685306335;13869.930650346683;14102.435902563886;14525.418983240657;14533.34599943005;1889.9149538270674;1938.0890981238795;1939.660559402099;1947.5617985953188;1954.4234450837037;50977;68913.65543172252;75506.62246688732;75643;75917;10242.897571024194;10293.691189264335;10473.9476302618;10603.522841472151;10622.893771062345;14419.639509012359;14503.347349369304;14510.637234069021;14517.927410363092;14525.418983240552;7541.585212813238;7748.263914928073;7786.065672119269;7977.641006154737;7982.960085199653;7385.4460915431555;7394.926050739424;7533.643944090007;7604.581748003913;7653.770386888402;6417.647029413427;6499.2200935887995;6594.021793462038;6610.768623098168;6648.0083962217195;1820.9271629134687;1846.8245516675895;1849.4081893794125;1922.528980399811;1924.1726057795283;858.6179150278066;872.6727477196051;877.3945977275787;883.5449743382163;890.95099769512;17487.91256043737;17516.474505764847;17752.556186095193;18508.907455462904;18717.812821871878;1049.3231865446744;1052.3580615824342;1052.8683914510686;1054.7152268887357;1076.4294923690456;9507.904920950841;10122.240831937486;10157.39055656663;10177.389356638625;10202.234832387601;197000;296469;356707;455999;457821;79167.20832791599;81508.59245703733;81900.18099819045;82068;82095.18512633047;469.8707855339809;1042.3797840285017;1061.1298735037196;1066.424130969268;1074.983875241872;10128.898711013091;10459.320144190604;10976.945115274691;11023.338599684044;11136.721581960515;32534.51198231981;37736.811315944346;38057.23885522219;38438.23123537569;40191;54443.45556544384;54521.72739136279;54615.45384546104;54702.72648636731;55400.168997464265;8381.329493640616;8388.328933685412;8390.909181806326;8489.915100848912;8590.78523036929;12196.146269761031;12271.938640306742;12533.248005119722;12604.369781510704;12811.423485942969;10312.639057632947;11092.168087393638;11127.499262533043;11272.323660580392;11591.942040290081;10951.945240273748;10999.725006874893;11109.222354435113;11115.610953616584;11174.161937854857;10910.727231819268;10930.508127134457;11077.224594278645;11098.611548596084;11220.66338009861;8225.300849427866;8265.256126948603;8481.024682161631;8495.830083398421;8579.914200857911;10508.264421490423;11051.778964420828;11121.27711698737;11304.830427543759;11682.532698691957 21:27:27 INFO - PROCESS | 9607 | |3;mootools.html;1318.18272670944;1318.4989703912524;1319.6700824793802;1333.1734324718673;1337.1308649377904;7801.219878012196;8289.585520723955;8294.83410331793;8297.211764882326;8304.626291816867;7238;7441.330280274774;7852.450328477009;7995.6801727930815;8151.673933042686;2277.9088836446563;2307.90768369265;2310.318456055459;2311.722593288799;2316.061994892071;2279.806216471602;2286.1884031168943;2324.8140148788143;2330.3358542815217;2338.1348900906687;1890;1944.7763507196642;2238.7649296823843;2263.9320820375415;2306.3888069661552;2293.1629955066364;2295.2655150351893;2297.7242730872235;2299.1608063057033;2304.2396009316885;2215.235743668432;2221.1781640792847;2229.877356745378;2252.358077947777;2253.4141123307895;8261.17388261175;8298.087210406851;8325.167483251687;8328.458650187718;8344.457610255315;5007.749612519359;5154.304168937183;5156.407013193493;5209.29674493946;5210.869728256786;3253.3981213475345;3422.161570415239;3465.982670086659;3472.73954453417;3473.94789078164;1317.183346325275;1332.1274565159836;1398.3357904995046;1416.596270063027;1515.5907904865624;10725.14198864089;10774;10776.353418794897;10783.514741836636;10793.67618971432;4306.3325184595815;4424.80088396023;4508.909821803545;4516.096780643818;4542.909141817211;9530.332876698565;9535.904640953502;9539.52302384878;9540.33217674756;9542.61829526825;5068.011737711111;5109.156989096832;5140.897182056338;5146.408163061221;5163.612729045337;4331;4441.933370999438;4611.308303754397;4679.929801052919;4719.528047195253;3749.437584362313;3826.598207188261;3843.1929294847837;3879.262940041383;3928.2929072767174;1293.877081677258;1304.6673098360047;1309.2537253765263;1318.0510032776385;1324.8343957005375;919.4529255093208;935.3031991166489;938.1097089364935;942.6323733743845;962.2206013129247;5600.431879073866;5607.130894711327;5650.434956504317;5744.195812586241;5997.520198384119;579.2556564814194;589.3517131155694;591.624318557719;592.1177445606016;595.1966239014205;3753.11801726594;3784.6215378461934;3831;3856.5179352580926;3921.176552923861;9030.45817250954;9786.314957952874;9817.067378599022;9824.557894894748;9866.506674666238;9474.38416502925;9775.804483910282;9796.951015244878;9802.36284641496;9813.116819486138;7403.592802395812;7776.84446311082;7792.493487923267;7794.8051298716855;7795.103563090317;5671.546276297804;5705.657660540381;5735.225744524602;5803.564732645153;5894.2926848778425;8978.371513993958;9009.189172974464;9112.134347237003;9127.771805704644;9136.49749263797;9164.175224229852;9182.311326650395;9289.21041711462;9354.812903741751;9375.718728438158;6664.166979127609;6837.555558888656;6837.829054273484;7345.192028876712;7501.212372701006;7429.8142546436775;7472.850542989218;7610.238976102345;7783.143854176149;7833.804154896173;7102.644867756695;7241.601711905685;7243.927560724325;7302.415806735555;7367.668454919649;7022.964885175541;7063.964680176772;7105.502614816914;7283.8179045522165;7320.011798407147;6489.643069631215;6493.967530162319;6542.182227221598;6546.803595892131;6547.312532184052;4818.253170758538;4869.196582563979;4979.278004689372;5102.846914592568;5202.869928251824;4827.42070951488;4911.158315631638;4916.631252656137;5006.574441172395;5039 21:27:27 INFO - PROCESS | 9607 | |4;prototype.html;382.58489538850347;385.510401789727;392.8526802449082;393.15471735768114;400.3594249201278;5094.566961808247;5151.793928242871;5157.690538567688;5195.298634684316;5220.634555581111;5118.155504341788;5148.871278218046;5152.402755145898;5153.4846515348445;5155.561777248939;4054.1688953764406;4088.7751173685438;4110.815013324393;4113.362428823537;4129.256733787917;4100.466939297887;4142.3637663123045;4142.730722503043;4157.833686652531;4162.542120366757;4128.855490057848;4129.277376459123;4130.772807495601;4132.731372460796;4142.544320124784;3818;4117.485314335723;4118.341065429532;4131.07050913545;4143.81352839123;3511.683948444627;3519.7360197985254;3529.205928666055;3534.540509733744;3560.412531932228;4962.851114466572;4972.378452693413;4988.90022199554;5010.874728131826;5078.568321692661;4556.7721613919175;4575.330004548316;4612.377329060568;4620.930686039712;4621.214393553104;5192.584593232532;5313.343328335832;5338.492843179892;5343.385510666245;5353.437889021636;3846.9228615987568;3848.0764616491842;3862.246861861938;3869.3688291961453;3870.3807390817333;5320.9467905320835;5461.399246082928;5468.589855760794;5484.369297530795;5515.586331025149;3938.8424463021156;3942.56631770505;3944.861929832442;3955.624215699504;3967.8016099194924;3986.0901541874014;4019.437278780973;4026.1343811080174;4035.2131334389514;4037.8384864605086;4293.785310734451;4380;4499.820007199741;4618.468876079227;4627.5603817637275;5309.681419114865;5326.227696983916;5330.109020415119;5338.265063854263;5340.732963351817;1340.8726171013739;1340.9731805363838;1342.201390172865;1349.3725417680826;1370.287450525721;916.544683945164;947.8341290274312;960.9519524023771;964.1563631822156;972.0134063925121;5029.692280007224;5030.4596178929405;5045.243213517928;5045.31888195089;5053.9241911371355;5202.92195617066;5236.711980841014;5247.658902171346;5304.575633949275;5333.5199832015305;4938.925651226494;4955.306257124005;4958.281049247839;4962.280469331999;5212.9739351303;2849.50133726597;2871.0812539987005;2876.467853447119;2890.869910854017;2906.9127926161827;5650.63270887391;5661.2923384576925;5671.022713075668;5680.744366503517;5715.371309155989;5426.403095659553;5544.500994910478;5680.602357834912;5685.232493613309;5690.971545142248;5761.56788240892;5761.827145185651;5767.68277744728;5774.595778295563;5784.71076446176;5471.6169868108855;5546.334439867242;5547.6408279971665;5586.916196256978;5620.594851287178;5430.592705547021;5474.178873168898;5531.119419397424;5545.306836645419;5547;5550.58370622213;5616.803411880564;5678.233438485751;5781.566382521243;5814.186013957965;3998.0804414984136;4020.175863947942;4023.7786921719585;4044.6338891748655;4048.149888523443;5578.888422231452;5634.971825140848;5636.830895073154;5640.633358831663;5653.123765816305;5267.762075912235;5430.40265570779;5457.154141108134;5461.563074953915;5476.151196564539;5435.2934118564335;5445.727713614224;5449.16643345466;5449.3460784706085;5502.779888804563;5214.243934629381;5321.494458026481;5436.266104075899;5439.646422982493;5485.080221922295;5421.617487540652;5433.619646624698;5445.809396671098;5463.476057942526;5476.7535460903555;5317.760700768397;5335.919961200508;5355.571554275726;5367.704776237344;5376.623636345575 21:27:27 INFO - PROCESS | 9607 | |5;yui.html;737.8339873528454;739.589527812064;784.0865391818531;826.966921323147;837.5477242289164;15525.534233972985;16077.678446431079;16365.263563139657;16429.589260268498;16456.588585285375;14872.182029988346;15079.773803392958;15288.617784555392;15300.693986120272;15550.45573404931;803.2770506544099;824.72371755462;824.9876251856227;825.5211977053294;826.6982551368737;823.8640624297013;825.558326295432;826.1201820061646;826.2233500509502;826.4627991805314;825.686239229092;826.5330088500008;828.494618282847;833.008719623649;838.5052818836915;833.1834801894147;834.6244190114443;838.6771093129129;839.9664013439456;840.6511297811401;603.7645318325856;609.1684850179473;609.3814778000333;615.9753609855601;616.9784057558008;14045.578632641038;14155.292235388293;14626.122432653969;14735.410583576644;14735.60011798888;12152.513899444011;12224.511019559208;12595.937020314932;12719.618411447671;12741.108122431433;11114.166437517219;11293.153013523937;11308.886911130865;11315.490802913806;11417.54329826806;3742.2141350316465;3744.157564547982;3745.8688945886765;3768.5854555998817;3877.3602355611292;54181.45818541843;54957.450425496034;67603.66198168977;78118.60940695317;79982.20017799806;11101.333919964827;11273.492692828842;11388.145889058354;11489.712757181138;11609.6517104487;22130.114795408004;22646.207382741854;23597.646035309142;23686.7631323688;23691.52616947686;8893.599788009555;8900.688475903313;9160.770980725536;9315.627374904929;9448.527573621428;4631.745254011062;4750.904981900343;4770.809167633325;4808.471068182497;4875.414950205927;3458.602260739997;3537.5047493350953;3540.2742437800703;3544.2557063017057;3548.964510354915;609.5397974529202;645.1000853808911;673.3266733266734;688.5249178067218;692.3664846665346;671.8689855478135;682.737146198717;687.3470203306879;703.3190674288454;706.8939659051183;8921.330900182513;9616.567254473566;9801.313908026512;10253.794924101625;10352.275340726228;723.4104205072849;735.569691730333;736.1938677148514;762.4396068889473;763.6792547130219;4728.581425572314;4807.95192048082;4811.388184958008;4828.227483602606;4881.286749590064;66723.66638166875;70685.64657176682;82892;83638;85858.57070714732;44334;56264;72673;73971.26028739751;75668.62165689246;53573.73213133909;60591.39408605946;60826;60867;60997.695011525546;9046.536607299136;9483.905160948578;9648.272192468195;9708.88302988933;9808.852867206855;40592;40840.38739418852;42123.368149477166;42170.36744448897;42376.57623423726;51307;52328;52686;53083.73458132685;53206.20190697065;13655.658608534866;13665.111767735065;13705.725885482434;13711.572106973257;14065.437382504586;14617.19605421712;14773.630659233606;14882.553523394316;14901.0894852469;14932.402703891723;12731.490740370282;13089.869101308865;13521.797173042216;13604.93197534006;14201.857981420053;12967.610971670865;13030.153040052366;14080.436782528584;14452.421903123757;14878.553643390716;12648.05139614551;12756.744865102832;12780.169288996185;12802.295873727035;13087.541936032194;11413.828792567952;11997.580084696994;12150.392480375769;12158.209716368408;12212.633620991384;11684.12369072306;11741.354225517336;11901.880981190077;11907.285562866256;11929.761404772029 21:27:27 INFO - PROCESS | 9607 | __end_tp_report 21:27:27 INFO - PROCESS | 9607 | __start_cc_report 21:27:27 INFO - PROCESS | 9607 | _x_x_mozilla_cycle_collect,1105 21:27:27 INFO - PROCESS | 9607 | __end_cc_report 21:27:27 INFO - PROCESS | 9607 | __startTimestamp1482643647520__endTimestamp 21:27:27 INFO - PROCESS | 9607 | 21:27:27 INFO - PROCESS | 9607 | ------- Summary: start ------- 21:27:27 INFO - PROCESS | 9607 | Number of tests: 6 21:27:27 INFO - PROCESS | 9607 | 21:27:27 INFO - PROCESS | 9607 | [#0] dojo.html Cycles:165 Average:5338.96 Median:5516.00 stddev:612.17 (11.1%) stddev-sans-first:589.51 21:27:27 INFO - PROCESS | 9607 | Values: 3151.5 3183.8 3196.8 3209.1 3219.2 5296.2 5364.4 5383.1 5386.5 5414.0 5376.3 5380.0 5387.8 5444.3 5455.9 4817.0 4840.6 4867.4 4872.4 4913.3 4856.8 4952.4 4959.4 4990.2 5012.2 4757.2 4936.5 4940.0 4952.6 5024.5 4781.8 4826.2 4860.8 4897.6 4923.5 4879.5 4909.1 4915.0 4919.0 4926.8 5389.3 5432.3 5440.1 5445.0 5456.9 5514.6 5541.3 5683.0 5712.6 5725.5 5701.7 5729.1 5737.3 5760.9 5773.1 5359.0 5366.5 5369.0 5369.2 5370.5 5785.6 5810.2 5874.5 5881.7 5934.4 5725.5 5727.0 5730.7 5773.8 5793.9 5793.2 5796.2 5822.2 5850.4 5850.7 5559.2 5565.0 5567.1 5601.5 5744.6 5633.6 5638.3 5650.1 5695.9 5697.0 4305.0 4309.1 4311.1 4317.1 4346.8 3836.6 3846.3 3851.5 3852.7 3869.6 5393.9 5430.6 5437.6 5469.3 5516.0 5742.2 5756.2 5761.6 5761.7 5770.7 5171.2 5465.1 5467.0 5474.8 5477.1 4765.1 4817.0 4821.4 4825.8 4827.3 6030.9 6045.2 6051.2 6051.2 6051.9 5797.7 5830.4 5834.3 5841.2 5848.3 5991.4 6021.3 6042.5 6054.2 6058.1 5670.7 5670.8 5672.5 5673.0 5687.1 5758.7 5760.4 5768.9 5769.4 5773.8 5529.0 5563.2 5570.9 5639.7 5642.6 5596.7 5627.4 5630.9 5634.7 5638.5 5446.8 5484.8 5534.7 5534.9 5601.8 5617.2 5631.3 5642.5 5650.4 5677.1 5494.6 5498.8 5506.4 5509.6 5511.8 21:27:27 INFO - PROCESS | 9607 | 21:27:27 INFO - PROCESS | 9607 | [#1] ext.html Cycles:180 Average:62180.00 Median:36155.23 stddev:140688.43 (389.1%) stddev-sans-first:141007.01 21:27:27 INFO - PROCESS | 9607 | Values: 553.9 594.0 594.1 599.0 601.1 31557.4 32637.0 36298.5 41982.0 43006.8 44746.6 45284.8 45304.5 45331.5 45342.5 8813.3 8980.8 8983.0 8984.1 8988.7 8485.1 8664.4 8932.9 9040.4 10455.9 10386.9 10715.8 10735.1 10736.0 10750.1 10764.9 10819.1 10829.8 10867.1 10876.0 10551.9 10635.5 10643.5 10648.1 10652.7 42732.0 42992.6 43035.1 43146.0 43216.0 4122.5 4171.4 4207.3 4252.2 4263.7 32274.2 32369.5 32637.3 32898.8 32989.7 3956.6 4071.7 4090.3 4123.2 4249.9 324009.0 324625.0 325905.0 326437.5 327119.0 52872.0 53208.0 53310.2 53343.7 53345.5 55560.4 55906.0 55929.7 56014.0 56033.0 15578.0 16175.7 16202.7 16205.4 16205.4 12410.1 12487.1 12496.9 12511.3 12511.7 15295.0 15583.1 16298.3 16461.9 16745.1 6397.6 6640.3 6654.7 6745.5 6883.5 876.6 880.5 888.1 900.2 905.7 30334.0 31570.0 31634.5 31688.7 32218.5 1043.2 1047.1 1050.3 1051.5 1158.2 15211.5 16509.8 16641.3 16805.2 16918.7 532301.0 795449.4 800771.0 806106.0 808170.0 47897.8 49199.3 49330.8 49335.8 49383.5 1342.1 1353.8 1375.8 1376.8 1378.8 27398.0 28358.9 28755.0 29140.7 29222.1 44590.1 45206.3 45318.9 45334.0 45345.8 374394.0 374731.0 376332.0 376466.0 377822.0 25691.0 25858.2 25950.2 26079.5 26095.2 43166.0 43333.8 43417.6 43481.3 43994.0 35882.0 35900.6 36064.8 36157.8 36271.8 34869.5 35469.6 36041.8 36152.6 39593.6 35581.8 36207.3 36542.3 37117.9 37123.3 29950.3 33126.3 33529.0 33704.5 33879.7 25102.0 25466.6 25849.7 25868.6 26077.2 21:27:27 INFO - PROCESS | 9607 | 21:27:27 INFO - PROCESS | 9607 | [#2] jquery.html Cycles:180 Average:23991.85 Median:2809.20 stddev:60901.52 (2167.9%) stddev-sans-first:61046.55 21:27:27 INFO - PROCESS | 9607 | Values: 376.4 392.8 395.4 404.2 422.7 13664.3 15428.6 18536.4 18632.3 19144.1 18129.4 18326.5 18505.6 18556.4 18560.7 5544.1 5638.0 5762.2 5765.2 5769.3 5500.1 5698.5 5700.1 5701.9 5702.4 5460.3 5691.0 5693.3 5707.7 5708.1 5581.5 5643.6 5677.9 5681.4 5682.4 4931.0 5062.1 5114.0 5118.4 5119.1 21465.5 21502.7 21515.5 21648.8 21827.9 2789.9 2806.8 2811.6 2821.6 2837.9 13265.7 13869.9 14102.4 14525.4 14533.3 1889.9 1938.1 1939.7 1947.6 1954.4 50977.0 68913.7 75506.6 75643.0 75917.0 10242.9 10293.7 10473.9 10603.5 10622.9 14419.6 14503.3 14510.6 14517.9 14525.4 7541.6 7748.3 7786.1 7977.6 7983.0 7385.4 7394.9 7533.6 7604.6 7653.8 6417.6 6499.2 6594.0 6610.8 6648.0 1820.9 1846.8 1849.4 1922.5 1924.2 858.6 872.7 877.4 883.5 891.0 17487.9 17516.5 17752.6 18508.9 18717.8 1049.3 1052.4 1052.9 1054.7 1076.4 9507.9 10122.2 10157.4 10177.4 10202.2 197000.0 296469.0 356707.0 455999.0 457821.0 79167.2 81508.6 81900.2 82068.0 82095.2 469.9 1042.4 1061.1 1066.4 1075.0 10128.9 10459.3 10976.9 11023.3 11136.7 32534.5 37736.8 38057.2 38438.2 40191.0 54443.5 54521.7 54615.5 54702.7 55400.2 8381.3 8388.3 8390.9 8489.9 8590.8 12196.1 12271.9 12533.2 12604.4 12811.4 10312.6 11092.2 11127.5 11272.3 11591.9 10951.9 10999.7 11109.2 11115.6 11174.2 10910.7 10930.5 11077.2 11098.6 11220.7 8225.3 8265.3 8481.0 8495.8 8579.9 10508.3 11051.8 11121.3 11304.8 11682.5 21:27:27 INFO - PROCESS | 9607 | 21:27:27 INFO - PROCESS | 9607 | [#3] mootools.html Cycles:180 Average:5395.67 Median:5603.78 stddev:2931.10 (52.3%) stddev-sans-first:2923.30 21:27:27 INFO - PROCESS | 9607 | Values: 1318.2 1318.5 1319.7 1333.2 1337.1 7801.2 8289.6 8294.8 8297.2 8304.6 7238.0 7441.3 7852.5 7995.7 8151.7 2277.9 2307.9 2310.3 2311.7 2316.1 2279.8 2286.2 2324.8 2330.3 2338.1 1890.0 1944.8 2238.8 2263.9 2306.4 2293.2 2295.3 2297.7 2299.2 2304.2 2215.2 2221.2 2229.9 2252.4 2253.4 8261.2 8298.1 8325.2 8328.5 8344.5 5007.7 5154.3 5156.4 5209.3 5210.9 3253.4 3422.2 3466.0 3472.7 3473.9 1317.2 1332.1 1398.3 1416.6 1515.6 10725.1 10774.0 10776.4 10783.5 10793.7 4306.3 4424.8 4508.9 4516.1 4542.9 9530.3 9535.9 9539.5 9540.3 9542.6 5068.0 5109.2 5140.9 5146.4 5163.6 4331.0 4441.9 4611.3 4679.9 4719.5 3749.4 3826.6 3843.2 3879.3 3928.3 1293.9 1304.7 1309.3 1318.1 1324.8 919.5 935.3 938.1 942.6 962.2 5600.4 5607.1 5650.4 5744.2 5997.5 579.3 589.4 591.6 592.1 595.2 3753.1 3784.6 3831.0 3856.5 3921.2 9030.5 9786.3 9817.1 9824.6 9866.5 9474.4 9775.8 9797.0 9802.4 9813.1 7403.6 7776.8 7792.5 7794.8 7795.1 5671.5 5705.7 5735.2 5803.6 5894.3 8978.4 9009.2 9112.1 9127.8 9136.5 9164.2 9182.3 9289.2 9354.8 9375.7 6664.2 6837.6 6837.8 7345.2 7501.2 7429.8 7472.9 7610.2 7783.1 7833.8 7102.6 7241.6 7243.9 7302.4 7367.7 7023.0 7064.0 7105.5 7283.8 7320.0 6489.6 6494.0 6542.2 6546.8 6547.3 4818.3 4869.2 4979.3 5102.8 5202.9 4827.4 4911.2 4916.6 5006.6 5039.0 21:27:27 INFO - PROCESS | 9607 | 21:27:27 INFO - PROCESS | 9607 | [#4] prototype.html Cycles:180 Average:4546.48 Median:5156.63 stddev:1320.80 (25.6%) stddev-sans-first:1287.00 21:27:27 INFO - PROCESS | 9607 | Values: 382.6 385.5 392.9 393.2 400.4 5094.6 5151.8 5157.7 5195.3 5220.6 5118.2 5148.9 5152.4 5153.5 5155.6 4054.2 4088.8 4110.8 4113.4 4129.3 4100.5 4142.4 4142.7 4157.8 4162.5 4128.9 4129.3 4130.8 4132.7 4142.5 3818.0 4117.5 4118.3 4131.1 4143.8 3511.7 3519.7 3529.2 3534.5 3560.4 4962.9 4972.4 4988.9 5010.9 5078.6 4556.8 4575.3 4612.4 4620.9 4621.2 5192.6 5313.3 5338.5 5343.4 5353.4 3846.9 3848.1 3862.2 3869.4 3870.4 5320.9 5461.4 5468.6 5484.4 5515.6 3938.8 3942.6 3944.9 3955.6 3967.8 3986.1 4019.4 4026.1 4035.2 4037.8 4293.8 4380.0 4499.8 4618.5 4627.6 5309.7 5326.2 5330.1 5338.3 5340.7 1340.9 1341.0 1342.2 1349.4 1370.3 916.5 947.8 961.0 964.2 972.0 5029.7 5030.5 5045.2 5045.3 5053.9 5202.9 5236.7 5247.7 5304.6 5333.5 4938.9 4955.3 4958.3 4962.3 5213.0 2849.5 2871.1 2876.5 2890.9 2906.9 5650.6 5661.3 5671.0 5680.7 5715.4 5426.4 5544.5 5680.6 5685.2 5691.0 5761.6 5761.8 5767.7 5774.6 5784.7 5471.6 5546.3 5547.6 5586.9 5620.6 5430.6 5474.2 5531.1 5545.3 5547.0 5550.6 5616.8 5678.2 5781.6 5814.2 3998.1 4020.2 4023.8 4044.6 4048.1 5578.9 5635.0 5636.8 5640.6 5653.1 5267.8 5430.4 5457.2 5461.6 5476.2 5435.3 5445.7 5449.2 5449.3 5502.8 5214.2 5321.5 5436.3 5439.6 5485.1 5421.6 5433.6 5445.8 5463.5 5476.8 5317.8 5335.9 5355.6 5367.7 5376.6 21:27:27 INFO - PROCESS | 9607 | 21:27:27 INFO - PROCESS | 9607 | [#5] yui.html Cycles:180 Average:16996.14 Median:4760.86 stddev:21088.85 (443.0%) stddev-sans-first:21112.67 21:27:27 INFO - PROCESS | 9607 | Values: 737.8 739.6 784.1 827.0 837.5 15525.5 16077.7 16365.3 16429.6 16456.6 14872.2 15079.8 15288.6 15300.7 15550.5 803.3 824.7 825.0 825.5 826.7 823.9 825.6 826.1 826.2 826.5 825.7 826.5 828.5 833.0 838.5 833.2 834.6 838.7 840.0 840.7 603.8 609.2 609.4 616.0 617.0 14045.6 14155.3 14626.1 14735.4 14735.6 12152.5 12224.5 12595.9 12719.6 12741.1 11114.2 11293.2 11308.9 11315.5 11417.5 3742.2 3744.2 3745.9 3768.6 3877.4 54181.5 54957.5 67603.7 78118.6 79982.2 11101.3 11273.5 11388.1 11489.7 11609.7 22130.1 22646.2 23597.6 23686.8 23691.5 8893.6 8900.7 9160.8 9315.6 9448.5 4631.7 4750.9 4770.8 4808.5 4875.4 3458.6 3537.5 3540.3 3544.3 3549.0 609.5 645.1 673.3 688.5 692.4 671.9 682.7 687.3 703.3 706.9 8921.3 9616.6 9801.3 10253.8 10352.3 723.4 735.6 736.2 762.4 763.7 4728.6 4808.0 4811.4 4828.2 4881.3 66723.7 70685.6 82892.0 83638.0 85858.6 44334.0 56264.0 72673.0 73971.3 75668.6 53573.7 60591.4 60826.0 60867.0 60997.7 9046.5 9483.9 9648.3 9708.9 9808.9 40592.0 40840.4 42123.4 42170.4 42376.6 51307.0 52328.0 52686.0 53083.7 53206.2 13655.7 13665.1 13705.7 13711.6 14065.4 14617.2 14773.6 14882.6 14901.1 14932.4 12731.5 13089.9 13521.8 13604.9 14201.9 12967.6 13030.2 14080.4 14452.4 14878.6 12648.1 12756.7 12780.2 12802.3 13087.5 11413.8 11997.6 12150.4 12158.2 12212.6 11684.1 11741.4 11901.9 11907.3 11929.8 21:27:27 INFO - PROCESS | 9607 | -------- Summary: end -------- 21:27:27 INFO - PROCESS | 9607 | 21:27:28 INFO - TEST-INFO | 9607: exit 0 21:27:28 INFO - TEST-OK | dromaeo_css | took 1115380ms 21:27:28 INFO - TEST-START | kraken 21:27:28 INFO - operating with platform_type : linux_ 21:27:28 INFO - Initialising browser for kraken test... 21:27:28 INFO - TEST-INFO | started process 9921 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSeKJSK/profile http://localhost:44786/getInfo.html) 21:27:28 INFO - PROCESS | 9921 | 1482643648806 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 21:27:31 INFO - PROCESS | 9921 | __metrics Screen width/height:1600/1200 21:27:31 INFO - PROCESS | 9921 | colorDepth:24 21:27:31 INFO - PROCESS | 9921 | Browser inner width/height: 1024/697 21:27:31 INFO - PROCESS | 9921 | __metrics 21:27:32 INFO - PROCESS | 9921 | 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" 21:27:32 INFO - PROCESS | 9921 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:27:32 INFO - PROCESS | 9921 | console.error: PushService: 21:27:32 INFO - PROCESS | 9921 | stateChangeProcessEnqueue: Error transitioning state 21:27:32 INFO - PROCESS | 9921 | UnknownError 21:27:33 INFO - TEST-INFO | 9921: exit 0 21:27:33 INFO - Browser initialized. 21:27:33 INFO - Running cycle 1/1 for kraken test... 21:27:33 INFO - TEST-INFO | started process 10004 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSeKJSK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:27:45 INFO - PROCESS | 10004 | RSS: Main: 147070976 21:27:45 INFO - PROCESS | 10004 | 21:27:47 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:44786/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44786/tests/kraken/driver.html?testName=audio-beat-detection) 21:27:48 INFO - PROCESS | 10004 | RSS: Main: 201158656 21:27:48 INFO - PROCESS | 10004 | 21:27:51 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:44786/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44786/tests/kraken/driver.html?testName=audio-dft) 21:27:51 INFO - PROCESS | 10004 | RSS: Main: 180727808 21:27:51 INFO - PROCESS | 10004 | 21:27:54 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:44786/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44786/tests/kraken/driver.html?testName=audio-fft) 21:27:55 INFO - PROCESS | 10004 | RSS: Main: 262930432 21:27:55 INFO - PROCESS | 10004 | 21:27:57 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:44786/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44786/tests/kraken/driver.html?testName=audio-oscillator) 21:27:58 INFO - PROCESS | 10004 | RSS: Main: 175173632 21:27:58 INFO - PROCESS | 10004 | 21:28:00 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:44786/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44786/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:28:00 INFO - PROCESS | 10004 | RSS: Main: 178372608 21:28:00 INFO - PROCESS | 10004 | 21:28:03 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:44786/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44786/tests/kraken/driver.html?testName=imaging-darkroom) 21:28:04 INFO - PROCESS | 10004 | RSS: Main: 217333760 21:28:04 INFO - PROCESS | 10004 | 21:28:07 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:44786/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44786/tests/kraken/driver.html?testName=imaging-desaturate) 21:28:08 INFO - PROCESS | 10004 | RSS: Main: 216907776 21:28:08 INFO - PROCESS | 10004 | 21:28:11 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:44786/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44786/tests/kraken/driver.html?testName=json-parse-financial) 21:28:11 INFO - PROCESS | 10004 | RSS: Main: 215330816 21:28:11 INFO - PROCESS | 10004 | 21:28:13 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:44786/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44786/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:28:13 INFO - PROCESS | 10004 | RSS: Main: 177721344 21:28:13 INFO - PROCESS | 10004 | 21:28:15 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:44786/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44786/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:28:16 INFO - PROCESS | 10004 | RSS: Main: 184549376 21:28:16 INFO - PROCESS | 10004 | 21:28:18 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:44786/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44786/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:28:18 INFO - PROCESS | 10004 | RSS: Main: 193515520 21:28:18 INFO - PROCESS | 10004 | 21:28:21 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:44786/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44786/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:28:22 INFO - PROCESS | 10004 | RSS: Main: 202190848 21:28:22 INFO - PROCESS | 10004 | 21:28:24 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:44786/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44786/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:28:25 INFO - PROCESS | 10004 | RSS: Main: 199118848 21:28:25 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:44786/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44786/tests/kraken/driver.html?testName=ai-astar) 21:28:27 INFO - PROCESS | 10004 | RSS: Main: 250728448 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | __start_tp_report 21:28:27 INFO - PROCESS | 10004 | _x_x_mozilla_page_load 21:28:27 INFO - PROCESS | 10004 | _x_x_mozilla_page_load_details 21:28:27 INFO - PROCESS | 10004 | |i|pagename|runs| 21:28:27 INFO - PROCESS | 10004 | |0;ai-astar;111;111;112;111;111;112;110;111;112;110 21:28:27 INFO - PROCESS | 10004 | |1;audio-beat-detection;142;118;121;161;119;121;120;118;139;120 21:28:27 INFO - PROCESS | 10004 | |2;audio-dft;160;162;159;159;160;159;160;160;161;162 21:28:27 INFO - PROCESS | 10004 | |3;audio-fft;76;76;75;111;76;77;75;75;97;75 21:28:27 INFO - PROCESS | 10004 | |4;audio-oscillator;82;84;107;84;83;84;97;84;84;110 21:28:27 INFO - PROCESS | 10004 | |5;imaging-gaussian-blur;117;117;116;119;117;117;117;117;116;117 21:28:27 INFO - PROCESS | 10004 | |6;imaging-darkroom;146;146;147;148;147;149;149;146;146;147 21:28:27 INFO - PROCESS | 10004 | |7;imaging-desaturate;96;95;96;96;96;96;96;95;96;96 21:28:27 INFO - PROCESS | 10004 | |8;json-parse-financial;53;50;51;50;52;51;51;51;51;52 21:28:27 INFO - PROCESS | 10004 | |9;json-stringify-tinderbox;66;66;66;67;66;66;67;66;65;66 21:28:27 INFO - PROCESS | 10004 | |10;stanford-crypto-aes;87;81;84;86;83;85;81;81;80;80 21:28:27 INFO - PROCESS | 10004 | |11;stanford-crypto-ccm;133;124;126;124;127;124;133;123;158;123 21:28:27 INFO - PROCESS | 10004 | |12;stanford-crypto-pbkdf2;167;162;169;167;163;163;163;165;165;164 21:28:27 INFO - PROCESS | 10004 | |13;stanford-crypto-sha256-iterative;63;63;65;71;62;63;61;71;60;59 21:28:27 INFO - PROCESS | 10004 | __end_tp_report 21:28:27 INFO - PROCESS | 10004 | __start_cc_report 21:28:27 INFO - PROCESS | 10004 | _x_x_mozilla_cycle_collect,1574 21:28:27 INFO - PROCESS | 10004 | __end_cc_report 21:28:27 INFO - PROCESS | 10004 | __startTimestamp1482643707368__endTimestamp 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | ------- Summary: start ------- 21:28:27 INFO - PROCESS | 10004 | Number of tests: 14 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.00 stddev:0.74 (0.7%) stddev-sans-first:0.78 21:28:27 INFO - PROCESS | 10004 | Values: 111.0 111.0 112.0 111.0 111.0 112.0 110.0 111.0 112.0 110.0 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:121.00 stddev:14.58 (12.0%) stddev-sans-first:14.54 21:28:27 INFO - PROCESS | 10004 | Values: 142.0 118.0 121.0 161.0 119.0 121.0 120.0 118.0 139.0 120.0 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:1.14 (0.7%) stddev-sans-first:1.20 21:28:27 INFO - PROCESS | 10004 | Values: 160.0 162.0 159.0 159.0 160.0 159.0 160.0 160.0 161.0 162.0 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.00 stddev:12.43 (16.4%) stddev-sans-first:13.03 21:28:27 INFO - PROCESS | 10004 | Values: 76.0 76.0 75.0 111.0 76.0 77.0 75.0 75.0 97.0 75.0 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:84.00 stddev:10.70 (12.7%) stddev-sans-first:10.96 21:28:27 INFO - PROCESS | 10004 | Values: 82.0 84.0 107.0 84.0 83.0 84.0 97.0 84.0 84.0 110.0 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 21:28:27 INFO - PROCESS | 10004 | Values: 117.0 117.0 116.0 119.0 117.0 117.0 117.0 117.0 116.0 117.0 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:1.20 (0.8%) stddev-sans-first:1.20 21:28:27 INFO - PROCESS | 10004 | Values: 146.0 146.0 147.0 148.0 147.0 149.0 149.0 146.0 146.0 147.0 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 21:28:27 INFO - PROCESS | 10004 | Values: 96.0 95.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 96.0 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.00 stddev:0.92 (1.8%) stddev-sans-first:0.71 21:28:27 INFO - PROCESS | 10004 | Values: 53.0 50.0 51.0 50.0 52.0 51.0 51.0 51.0 51.0 52.0 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 21:28:27 INFO - PROCESS | 10004 | Values: 66.0 66.0 66.0 67.0 66.0 66.0 67.0 66.0 65.0 66.0 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:83.50 stddev:2.57 (3.1%) stddev-sans-first:2.24 21:28:27 INFO - PROCESS | 10004 | Values: 87.0 81.0 84.0 86.0 83.0 85.0 81.0 81.0 80.0 80.0 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | [#11] stanford-crypto-ccm Cycles:10 Average:129.50 Median:126.50 stddev:10.70 (8.5%) stddev-sans-first:11.27 21:28:27 INFO - PROCESS | 10004 | Values: 133.0 124.0 126.0 124.0 127.0 124.0 133.0 123.0 158.0 123.0 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.80 Median:165.00 stddev:2.25 (1.4%) stddev-sans-first:2.24 21:28:27 INFO - PROCESS | 10004 | Values: 167.0 162.0 169.0 167.0 163.0 163.0 163.0 165.0 165.0 164.0 21:28:27 INFO - PROCESS | 10004 | 21:28:27 INFO - PROCESS | 10004 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:63.00 stddev:4.16 (6.6%) stddev-sans-first:4.40 21:28:27 INFO - PROCESS | 10004 | Values: 63.0 63.0 65.0 71.0 62.0 63.0 61.0 71.0 60.0 59.0 21:28:27 INFO - PROCESS | 10004 | -------- Summary: end -------- 21:28:27 INFO - PROCESS | 10004 | 21:28:28 INFO - TEST-INFO | 10004: exit 0 21:28:28 INFO - TEST-OK | kraken | took 60116ms 21:28:28 INFO - SUITE-END | took 1175s 21:28:28 INFO - Completed test suite (00:19:35) 21:28:28 INFO - javascript benchmark 21:28:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3151.5272709093633, 3183.8408079596015, 3196.8081915085095, 3209.0533292678656, 3219.1791093271213, 5296.152615581508, 5364.409914909362, 5383.057964856149, 5386.542143917762, 5413.972930135349, 5376.301080859493, 5380.031594313022, 5387.838364849051, 5444.292242008543, 5455.863603409907, 4817.012512434942, 4840.6369522285795, 4867.3915760529935, 4872.366592342991, 4913.263010548429, 4856.830010949618, 4952.430470495885, 4959.429665588467, 4990.201567749161, 5012.248162775594, 4757.215059515176, 4936.531029552187, 4939.975300123477, 4952.603791696655, 5024.547790698836, 4781.760911954423, 4826.155422800995, 4860.8055677772845, 4897.608191344684, 4923.532264434873, 4879.487653796371, 4909.091818013643, 4914.975425122887, 4918.9754051229875, 4926.827561035347, 5389.272448219479, 5432.266644003049, 5440.129579267338, 5445.019896418643, 5456.863578410532, 5514.641548299388, 5541.251930989345, 5682.971585142089, 5712.57155713319, 5725.513331366838, 5701.714914254312, 5729.083346664514, 5737.282839645044, 5760.855978600526, 5773.134029895507, 5359.0, 5366.4901834326065, 5368.9731551342775, 5369.221462887859, 5370.516653501165, 5785.566082543742, 5810.186573879576, 5874.488715713522, 5881.735321910524, 5934.406559344031, 5725.51333136688, 5727.026405511073, 5730.713464326767, 5773.769049237983, 5793.8551536211935, 5793.217915581343, 5796.188533605298, 5822.155787410802, 5850.444207800252, 5850.707464626752, 5559.249501317351, 5565.02612042899, 5567.109262517978, 5601.467860553241, 5744.597878148573, 5633.605647604629, 5638.323401191801, 5650.0959846424375, 5695.943040569541, 5697.0, 4304.966807966066, 4309.095090031066, 4311.07311927937, 4317.07182955666, 4346.847860324937, 3836.55879573847, 3846.3461211594094, 3851.4607954886337, 3852.653261206449, 3869.6323849234277, 5393.892122157535, 5430.5927055471, 5437.646552974044, 5469.261649677323, 5516.0, 5742.224799652077, 5756.1653560233535, 5761.567882408753, 5761.711914404263, 5770.740316685759, 5171.172612382076, 5465.098258787335, 5466.972665136649, 5474.808381706621, 5477.123660214346, 4765.070811191785, 4816.988432429159, 4821.421429428491, 4825.831095911626, 4827.25177597473, 6030.939690603213, 6045.153678484928, 6051.183090282843, 6051.213342265565, 6051.909221361683, 5797.6521408713925, 5830.416958304305, 5834.329052158886, 5841.153032810304, 5848.298204215351, 5991.400859913973, 6021.307549631849, 6042.456178943916, 6054.243219597551, 6058.091286307089, 5670.716464176692, 5670.773169073191, 5672.517835983823, 5672.971635141963, 5687.146927960674, 5758.71206439668, 5760.366359700513, 5768.913466297925, 5769.3942136076785, 5773.769049238152, 5529.032419326682, 5563.248961390323, 5570.888582228414, 5639.661620302795, 5642.576806739593, 5596.664200147841, 5627.380988091225, 5630.859228519156, 5634.718264086861, 5638.520725738358, 5446.809361672323, 5484.808031718871, 5534.667919924817, 5534.91697624544, 5601.831945041656, 5617.157426386075, 5631.2960879890015, 5642.5485961122195, 5650.378458369658, 5677.148427735872, 5494.615376923737, 5498.780048798003, 5506.421825708243, 5509.586780991522, 5511.834644960658], "value": 5297.707280521065, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.8919910617429, 593.9931815572605, 594.0554518315877, 598.9880202395952, 601.1463721515447, 31557.368852622963, 32637.0, 36298.45552316718, 41982.0, 43006.78496607512, 44746.55253447464, 45284.77357613215, 45304.546954530444, 45331.54668453323, 45342.546574534244, 8813.25087367573, 8980.775480613005, 8983.0, 8984.056674049229, 8988.730338089868, 8485.0666426693, 8664.436811607256, 8932.8660070099, 9040.412373195753, 10455.947720261389, 10386.948065259701, 10715.78568428631, 10735.087517561016, 10736.0, 10750.139988800876, 10764.892351076507, 10819.080378167866, 10829.83755243672, 10867.130629549696, 10876.0, 10551.94724026375, 10635.46822658864, 10643.521041553071, 10648.094911932418, 10652.680419587425, 42732.0, 42992.57007429917, 43035.13929721419, 43146.0, 43216.0, 4122.484689413824, 4171.395147703596, 4207.326827707583, 4252.213340532012, 4263.658907287409, 32274.193145171557, 32369.51445728316, 32637.347253054806, 32898.83550582256, 32989.6701032989, 3956.5647778744315, 4071.674266058738, 4090.3455447128317, 4123.154753275571, 4249.893752656178, 324009.0, 324625.0, 325905.0, 326437.5171862096, 327119.0, 52872.0, 53208.0, 53310.200346994825, 53343.73328133412, 53345.46654533405, 55560.44439555633, 55906.0, 55929.720351397984, 56014.0, 56033.0, 15578.0, 16175.67648647044, 16202.675946481004, 16205.432809851598, 16205.432809851834, 12410.131290809737, 12487.063470239587, 12496.875031249752, 12511.311877846623, 12511.687207819878, 15295.005824621365, 15583.142927139117, 16298.348066077462, 16461.917690411472, 16745.0649097462, 6397.61614303134, 6640.314625382863, 6654.70233304501, 6745.494087943423, 6883.483738719616, 876.6318146378536, 880.4629176202511, 888.0897080492547, 900.1808354397467, 905.7101727447154, 30334.0, 31570.0, 31634.525482117788, 31688.683113169034, 32218.516722249184, 1043.2436483549518, 1047.0681093826502, 1050.333038520545, 1051.4637534857125, 1158.1834806461425, 15211.467598633995, 16509.752353714703, 16641.334346625998, 16805.24376403065, 16918.74621880673, 532301.0, 795449.4323039302, 800771.0, 806106.0, 808170.0, 47897.76051119722, 49199.26201106986, 49330.75334623304, 49335.75332123388, 49383.50616493861, 1342.0806747378078, 1353.824002879639, 1375.848656647768, 1376.7590671632424, 1378.8138601288697, 27398.0, 28358.85820570884, 28755.0, 29140.7085929138, 29222.123336299945, 44590.10819783651, 45206.32190517211, 45318.86702832456, 45334.0, 45345.77327113343, 374394.0, 374731.0, 376332.0, 376466.0, 377822.0, 25691.0, 25858.22425327243, 25950.22149335523, 26079.478410431304, 26095.217143485726, 43166.0, 43333.78333108314, 43417.56582434135, 43481.34777978396, 43994.0, 35882.0, 35900.64099358973, 36064.81967590145, 36157.81921090378, 36271.818640906626, 34869.476957845145, 35469.645303547666, 36041.81979090193, 36152.63847361493, 39593.60406395899, 35581.82209088938, 36207.27585448329, 36542.269154617294, 37117.875550533856, 37123.25753484861, 29950.25124371908, 33126.33747325088, 33529.0, 33704.494432583044, 33879.66120338765, 25102.0, 25466.61800072963, 25849.741502584733, 25868.611970820075, 26077.217683469527], "value": 19493.286613352215, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.3864900212653, 392.76434139516294, 395.3595175815179, 404.20170163926247, 422.7294531499839, 13664.31678416079, 15428.614284642876, 18536.351227707033, 18632.25470981159, 19144.13851376688, 18129.365472208476, 18326.541836454064, 18505.629887402243, 18556.44330670079, 18560.721589176173, 5544.140658197975, 5637.985162670718, 5762.222100016486, 5765.1928729977835, 5769.278840144982, 5500.147477141049, 5698.458646428583, 5700.144978253254, 5701.885962280772, 5702.429757024285, 5460.31746031746, 5691.03252447085, 5693.28833895763, 5707.714614269291, 5708.08670612702, 5581.49766521013, 5643.6049476536655, 5677.943220567783, 5681.431856814327, 5682.403347648478, 4931.0, 5062.11413002723, 5114.0, 5118.436971933084, 5119.078565858144, 21465.46336341588, 21502.677459838116, 21515.46211344713, 21648.78351216499, 21827.890860545594, 2789.888404463819, 2806.845623490707, 2811.550151975674, 2821.5908693239367, 2837.858107094637, 13265.734685306335, 13869.930650346683, 14102.435902563886, 14525.418983240657, 14533.34599943005, 1889.9149538270674, 1938.0890981238795, 1939.660559402099, 1947.5617985953188, 1954.4234450837037, 50977.0, 68913.65543172252, 75506.62246688732, 75643.0, 75917.0, 10242.897571024194, 10293.691189264335, 10473.9476302618, 10603.522841472151, 10622.893771062345, 14419.639509012359, 14503.347349369304, 14510.637234069021, 14517.927410363092, 14525.418983240552, 7541.585212813238, 7748.263914928073, 7786.065672119269, 7977.641006154737, 7982.960085199653, 7385.4460915431555, 7394.926050739424, 7533.643944090007, 7604.581748003913, 7653.770386888402, 6417.647029413427, 6499.2200935887995, 6594.021793462038, 6610.768623098168, 6648.0083962217195, 1820.9271629134687, 1846.8245516675895, 1849.4081893794125, 1922.528980399811, 1924.1726057795283, 858.6179150278066, 872.6727477196051, 877.3945977275787, 883.5449743382163, 890.95099769512, 17487.91256043737, 17516.474505764847, 17752.556186095193, 18508.907455462904, 18717.812821871878, 1049.3231865446744, 1052.3580615824342, 1052.8683914510686, 1054.7152268887357, 1076.4294923690456, 9507.904920950841, 10122.240831937486, 10157.39055656663, 10177.389356638625, 10202.234832387601, 197000.0, 296469.0, 356707.0, 455999.0, 457821.0, 79167.20832791599, 81508.59245703733, 81900.18099819045, 82068.0, 82095.18512633047, 469.8707855339809, 1042.3797840285017, 1061.1298735037196, 1066.424130969268, 1074.983875241872, 10128.898711013091, 10459.320144190604, 10976.945115274691, 11023.338599684044, 11136.721581960515, 32534.51198231981, 37736.811315944346, 38057.23885522219, 38438.23123537569, 40191.0, 54443.45556544384, 54521.72739136279, 54615.45384546104, 54702.72648636731, 55400.168997464265, 8381.329493640616, 8388.328933685412, 8390.909181806326, 8489.915100848912, 8590.78523036929, 12196.146269761031, 12271.938640306742, 12533.248005119722, 12604.369781510704, 12811.423485942969, 10312.639057632947, 11092.168087393638, 11127.499262533043, 11272.323660580392, 11591.942040290081, 10951.945240273748, 10999.725006874893, 11109.222354435113, 11115.610953616584, 11174.161937854857, 10910.727231819268, 10930.508127134457, 11077.224594278645, 11098.611548596084, 11220.66338009861, 8225.300849427866, 8265.256126948603, 8481.024682161631, 8495.830083398421, 8579.914200857911, 10508.264421490423, 11051.778964420828, 11121.27711698737, 11304.830427543759, 11682.532698691957], "value": 8821.637403085479, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.18272670944, 1318.4989703912524, 1319.6700824793802, 1333.1734324718673, 1337.1308649377904, 7801.219878012196, 8289.585520723955, 8294.83410331793, 8297.211764882326, 8304.626291816867, 7238.0, 7441.330280274774, 7852.450328477009, 7995.6801727930815, 8151.673933042686, 2277.9088836446563, 2307.90768369265, 2310.318456055459, 2311.722593288799, 2316.061994892071, 2279.806216471602, 2286.1884031168943, 2324.8140148788143, 2330.3358542815217, 2338.1348900906687, 1890.0, 1944.7763507196642, 2238.7649296823843, 2263.9320820375415, 2306.3888069661552, 2293.1629955066364, 2295.2655150351893, 2297.7242730872235, 2299.1608063057033, 2304.2396009316885, 2215.235743668432, 2221.1781640792847, 2229.877356745378, 2252.358077947777, 2253.4141123307895, 8261.17388261175, 8298.087210406851, 8325.167483251687, 8328.458650187718, 8344.457610255315, 5007.749612519359, 5154.304168937183, 5156.407013193493, 5209.29674493946, 5210.869728256786, 3253.3981213475345, 3422.161570415239, 3465.982670086659, 3472.73954453417, 3473.94789078164, 1317.183346325275, 1332.1274565159836, 1398.3357904995046, 1416.596270063027, 1515.5907904865624, 10725.14198864089, 10774.0, 10776.353418794897, 10783.514741836636, 10793.67618971432, 4306.3325184595815, 4424.80088396023, 4508.909821803545, 4516.096780643818, 4542.909141817211, 9530.332876698565, 9535.904640953502, 9539.52302384878, 9540.33217674756, 9542.61829526825, 5068.011737711111, 5109.156989096832, 5140.897182056338, 5146.408163061221, 5163.612729045337, 4331.0, 4441.933370999438, 4611.308303754397, 4679.929801052919, 4719.528047195253, 3749.437584362313, 3826.598207188261, 3843.1929294847837, 3879.262940041383, 3928.2929072767174, 1293.877081677258, 1304.6673098360047, 1309.2537253765263, 1318.0510032776385, 1324.8343957005375, 919.4529255093208, 935.3031991166489, 938.1097089364935, 942.6323733743845, 962.2206013129247, 5600.431879073866, 5607.130894711327, 5650.434956504317, 5744.195812586241, 5997.520198384119, 579.2556564814194, 589.3517131155694, 591.624318557719, 592.1177445606016, 595.1966239014205, 3753.11801726594, 3784.6215378461934, 3831.0, 3856.5179352580926, 3921.176552923861, 9030.45817250954, 9786.314957952874, 9817.067378599022, 9824.557894894748, 9866.506674666238, 9474.38416502925, 9775.804483910282, 9796.951015244878, 9802.36284641496, 9813.116819486138, 7403.592802395812, 7776.84446311082, 7792.493487923267, 7794.8051298716855, 7795.103563090317, 5671.546276297804, 5705.657660540381, 5735.225744524602, 5803.564732645153, 5894.2926848778425, 8978.371513993958, 9009.189172974464, 9112.134347237003, 9127.771805704644, 9136.49749263797, 9164.175224229852, 9182.311326650395, 9289.21041711462, 9354.812903741751, 9375.718728438158, 6664.166979127609, 6837.555558888656, 6837.829054273484, 7345.192028876712, 7501.212372701006, 7429.8142546436775, 7472.850542989218, 7610.238976102345, 7783.143854176149, 7833.804154896173, 7102.644867756695, 7241.601711905685, 7243.927560724325, 7302.415806735555, 7367.668454919649, 7022.964885175541, 7063.964680176772, 7105.502614816914, 7283.8179045522165, 7320.011798407147, 6489.643069631215, 6493.967530162319, 6542.182227221598, 6546.803595892131, 6547.312532184052, 4818.253170758538, 4869.196582563979, 4979.278004689372, 5102.846914592568, 5202.869928251824, 4827.42070951488, 4911.158315631638, 4916.631252656137, 5006.574441172395, 5039.0], "value": 4358.042643987616, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.58489538850347, 385.510401789727, 392.8526802449082, 393.15471735768114, 400.3594249201278, 5094.566961808247, 5151.793928242871, 5157.690538567688, 5195.298634684316, 5220.634555581111, 5118.155504341788, 5148.871278218046, 5152.402755145898, 5153.4846515348445, 5155.561777248939, 4054.1688953764406, 4088.7751173685438, 4110.815013324393, 4113.362428823537, 4129.256733787917, 4100.466939297887, 4142.3637663123045, 4142.730722503043, 4157.833686652531, 4162.542120366757, 4128.855490057848, 4129.277376459123, 4130.772807495601, 4132.731372460796, 4142.544320124784, 3818.0, 4117.485314335723, 4118.341065429532, 4131.07050913545, 4143.81352839123, 3511.683948444627, 3519.7360197985254, 3529.205928666055, 3534.540509733744, 3560.412531932228, 4962.851114466572, 4972.378452693413, 4988.90022199554, 5010.874728131826, 5078.568321692661, 4556.7721613919175, 4575.330004548316, 4612.377329060568, 4620.930686039712, 4621.214393553104, 5192.584593232532, 5313.343328335832, 5338.492843179892, 5343.385510666245, 5353.437889021636, 3846.9228615987568, 3848.0764616491842, 3862.246861861938, 3869.3688291961453, 3870.3807390817333, 5320.9467905320835, 5461.399246082928, 5468.589855760794, 5484.369297530795, 5515.586331025149, 3938.8424463021156, 3942.56631770505, 3944.861929832442, 3955.624215699504, 3967.8016099194924, 3986.0901541874014, 4019.437278780973, 4026.1343811080174, 4035.2131334389514, 4037.8384864605086, 4293.785310734451, 4380.0, 4499.820007199741, 4618.468876079227, 4627.5603817637275, 5309.681419114865, 5326.227696983916, 5330.109020415119, 5338.265063854263, 5340.732963351817, 1340.8726171013739, 1340.9731805363838, 1342.201390172865, 1349.3725417680826, 1370.287450525721, 916.544683945164, 947.8341290274312, 960.9519524023771, 964.1563631822156, 972.0134063925121, 5029.692280007224, 5030.4596178929405, 5045.243213517928, 5045.31888195089, 5053.9241911371355, 5202.92195617066, 5236.711980841014, 5247.658902171346, 5304.575633949275, 5333.5199832015305, 4938.925651226494, 4955.306257124005, 4958.281049247839, 4962.280469331999, 5212.9739351303, 2849.50133726597, 2871.0812539987005, 2876.467853447119, 2890.869910854017, 2906.9127926161827, 5650.63270887391, 5661.2923384576925, 5671.022713075668, 5680.744366503517, 5715.371309155989, 5426.403095659553, 5544.500994910478, 5680.602357834912, 5685.232493613309, 5690.971545142248, 5761.56788240892, 5761.827145185651, 5767.68277744728, 5774.595778295563, 5784.71076446176, 5471.6169868108855, 5546.334439867242, 5547.6408279971665, 5586.916196256978, 5620.594851287178, 5430.592705547021, 5474.178873168898, 5531.119419397424, 5545.306836645419, 5547.0, 5550.58370622213, 5616.803411880564, 5678.233438485751, 5781.566382521243, 5814.186013957965, 3998.0804414984136, 4020.175863947942, 4023.7786921719585, 4044.6338891748655, 4048.149888523443, 5578.888422231452, 5634.971825140848, 5636.830895073154, 5640.633358831663, 5653.123765816305, 5267.762075912235, 5430.40265570779, 5457.154141108134, 5461.563074953915, 5476.151196564539, 5435.2934118564335, 5445.727713614224, 5449.16643345466, 5449.3460784706085, 5502.779888804563, 5214.243934629381, 5321.494458026481, 5436.266104075899, 5439.646422982493, 5485.080221922295, 5421.617487540652, 5433.619646624698, 5445.809396671098, 5463.476057942526, 5476.7535460903555, 5317.760700768397, 5335.919961200508, 5355.571554275726, 5367.704776237344, 5376.623636345575], "value": 4143.90301383213, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.8339873528454, 739.589527812064, 784.0865391818531, 826.966921323147, 837.5477242289164, 15525.534233972985, 16077.678446431079, 16365.263563139657, 16429.589260268498, 16456.588585285375, 14872.182029988346, 15079.773803392958, 15288.617784555392, 15300.693986120272, 15550.45573404931, 803.2770506544099, 824.72371755462, 824.9876251856227, 825.5211977053294, 826.6982551368737, 823.8640624297013, 825.558326295432, 826.1201820061646, 826.2233500509502, 826.4627991805314, 825.686239229092, 826.5330088500008, 828.494618282847, 833.008719623649, 838.5052818836915, 833.1834801894147, 834.6244190114443, 838.6771093129129, 839.9664013439456, 840.6511297811401, 603.7645318325856, 609.1684850179473, 609.3814778000333, 615.9753609855601, 616.9784057558008, 14045.578632641038, 14155.292235388293, 14626.122432653969, 14735.410583576644, 14735.60011798888, 12152.513899444011, 12224.511019559208, 12595.937020314932, 12719.618411447671, 12741.108122431433, 11114.166437517219, 11293.153013523937, 11308.886911130865, 11315.490802913806, 11417.54329826806, 3742.2141350316465, 3744.157564547982, 3745.8688945886765, 3768.5854555998817, 3877.3602355611292, 54181.45818541843, 54957.450425496034, 67603.66198168977, 78118.60940695317, 79982.20017799806, 11101.333919964827, 11273.492692828842, 11388.145889058354, 11489.712757181138, 11609.6517104487, 22130.114795408004, 22646.207382741854, 23597.646035309142, 23686.7631323688, 23691.52616947686, 8893.599788009555, 8900.688475903313, 9160.770980725536, 9315.627374904929, 9448.527573621428, 4631.745254011062, 4750.904981900343, 4770.809167633325, 4808.471068182497, 4875.414950205927, 3458.602260739997, 3537.5047493350953, 3540.2742437800703, 3544.2557063017057, 3548.964510354915, 609.5397974529202, 645.1000853808911, 673.3266733266734, 688.5249178067218, 692.3664846665346, 671.8689855478135, 682.737146198717, 687.3470203306879, 703.3190674288454, 706.8939659051183, 8921.330900182513, 9616.567254473566, 9801.313908026512, 10253.794924101625, 10352.275340726228, 723.4104205072849, 735.569691730333, 736.1938677148514, 762.4396068889473, 763.6792547130219, 4728.581425572314, 4807.95192048082, 4811.388184958008, 4828.227483602606, 4881.286749590064, 66723.66638166875, 70685.64657176682, 82892.0, 83638.0, 85858.57070714732, 44334.0, 56264.0, 72673.0, 73971.26028739751, 75668.62165689246, 53573.73213133909, 60591.39408605946, 60826.0, 60867.0, 60997.695011525546, 9046.536607299136, 9483.905160948578, 9648.272192468195, 9708.88302988933, 9808.852867206855, 40592.0, 40840.38739418852, 42123.368149477166, 42170.36744448897, 42376.57623423726, 51307.0, 52328.0, 52686.0, 53083.73458132685, 53206.20190697065, 13655.658608534866, 13665.111767735065, 13705.725885482434, 13711.572106973257, 14065.437382504586, 14617.19605421712, 14773.630659233606, 14882.553523394316, 14901.0894852469, 14932.402703891723, 12731.490740370282, 13089.869101308865, 13521.797173042216, 13604.93197534006, 14201.857981420053, 12967.610971670865, 13030.153040052366, 14080.436782528584, 14452.421903123757, 14878.553643390716, 12648.05139614551, 12756.744865102832, 12780.169288996185, 12802.295873727035, 13087.541936032194, 11413.828792567952, 11997.580084696994, 12150.392480375769, 12158.209716368408, 12212.633620991384, 11684.12369072306, 11741.354225517336, 11901.880981190077, 11907.285562866256, 11929.761404772029], "value": 7220.211464069842, "unit": "score"}], "name": "dromaeo_css", "value": 7011.376245923264, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 112.0, 111.0, 111.0, 112.0, 110.0, 111.0, 112.0, 110.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 118.0, 121.0, 161.0, 119.0, 121.0, 120.0, 118.0, 139.0, 120.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 159.0, 159.0, 160.0, 159.0, 160.0, 160.0, 161.0, 162.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 111.0, 76.0, 77.0, 75.0, 75.0, 97.0, 75.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 107.0, 84.0, 83.0, 84.0, 97.0, 84.0, 84.0, 110.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 119.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 148.0, 147.0, 149.0, 149.0, 146.0, 146.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 51.0, 50.0, 52.0, 51.0, 51.0, 51.0, 51.0, 52.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 66.0, 66.0, 67.0, 66.0, 65.0, 66.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 81.0, 84.0, 86.0, 83.0, 85.0, 81.0, 81.0, 80.0, 80.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 124.0, 126.0, 124.0, 127.0, 124.0, 133.0, 123.0, 158.0, 123.0], "value": 129.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 162.0, 169.0, 167.0, 163.0, 163.0, 163.0, 165.0, 165.0, 164.0], "value": 164.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 65.0, 71.0, 62.0, 63.0, 61.0, 71.0, 60.0, 59.0], "value": 63.8, "unit": "score"}], "name": "kraken", "value": 1488.4999999999998, "alertThreshold": 2.0}]} 21:28:28 INFO - Return code: 0 21:28:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:28:28 INFO - # TBPL SUCCESS # 21:28:28 INFO - Running post-action listener: _resource_record_post_action 21:28:28 INFO - [mozharness: 2016-12-25 05:28:28.675106Z] Finished run-tests step (success) 21:28:28 INFO - Running post-run listener: _resource_record_post_run 21:28:28 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 12288; Write bytes: 413487104; Read time: 244; Write time: 274284 21:28:28 INFO - TinderboxPrint: CPU usage
13.0% 21:28:28 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 244 21:28:28 INFO - TinderboxPrint: I/O write bytes / time
413,487,104 / 274,284 21:28:28 INFO - TinderboxPrint: CPU idle
8,282.1 (87.0%) 21:28:28 INFO - TinderboxPrint: CPU user
1,197.2 (12.6%) 21:28:28 INFO - TinderboxPrint: Swap in / out
0 / 0 21:28:28 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 115986432; Read time: 188; Write time: 75168 21:28:28 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 296611840; Read time: 40; Write time: 199092 21:28:29 INFO - Running post-run listener: _upload_blobber_files 21:28:29 INFO - Blob upload gear active. 21:28:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:28:29 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 21:28:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:28:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:28:29 INFO - (blobuploader) - INFO - Open directory for files ... 21:28:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:28:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:28:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:30 INFO - (blobuploader) - INFO - Done attempting. 21:28:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:28:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:28:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:33 INFO - (blobuploader) - INFO - Done attempting. 21:28:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:28:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:28:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:34 INFO - (blobuploader) - INFO - Done attempting. 21:28:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:28:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:28:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:35 INFO - (blobuploader) - INFO - Done attempting. 21:28:35 INFO - (blobuploader) - INFO - Iteration through files over. 21:28:35 INFO - Return code: 0 21:28:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:28:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:28:35 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdc1bfaa35179a875b7f49874837197e0b76ee3ad2b2fa722663d69c1484a4e1bb79ef37dba11df8d325f62d34079c9812c7566c0a1955484cdfac78cc3225f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10cacdcc56465a54e83c2bdf4c1a2c683fef2b97774184984134321a215ae60e817ddac307769c33811a632ba3ba0e02b01e90b1c1ce175a98101148495c031a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b3dcbe1c322f00ad1b384aefa22e55d427f5947977fc070055be8e8bd737e79a54fc1a198fd9d74d85a10630b369de0a1c8423df741391a65543b73790a8116", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e690332b8f6c176c92a10919909356adea871e37b0c049e188c65188e1e8f270179dc63eec5c3ba19554cae2af819a3b626c30f3e794894a555797b3d7dc6af"} 21:28:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:28:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:28:35 INFO - Contents: 21:28:35 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdc1bfaa35179a875b7f49874837197e0b76ee3ad2b2fa722663d69c1484a4e1bb79ef37dba11df8d325f62d34079c9812c7566c0a1955484cdfac78cc3225f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10cacdcc56465a54e83c2bdf4c1a2c683fef2b97774184984134321a215ae60e817ddac307769c33811a632ba3ba0e02b01e90b1c1ce175a98101148495c031a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b3dcbe1c322f00ad1b384aefa22e55d427f5947977fc070055be8e8bd737e79a54fc1a198fd9d74d85a10630b369de0a1c8423df741391a65543b73790a8116", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e690332b8f6c176c92a10919909356adea871e37b0c049e188c65188e1e8f270179dc63eec5c3ba19554cae2af819a3b626c30f3e794894a555797b3d7dc6af"} 21:28:35 INFO - Running post-run listener: copy_logs_to_upload_dir 21:28:35 INFO - Copying logs to upload dir... 21:28:35 INFO - mkdir: /builds/slave/test/build/upload/logs 21:28:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.829674 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-12-24 21:28:35.410905) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-24 21:28:35.415052) ========= 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-1482640188.252823-602346170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdc1bfaa35179a875b7f49874837197e0b76ee3ad2b2fa722663d69c1484a4e1bb79ef37dba11df8d325f62d34079c9812c7566c0a1955484cdfac78cc3225f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10cacdcc56465a54e83c2bdf4c1a2c683fef2b97774184984134321a215ae60e817ddac307769c33811a632ba3ba0e02b01e90b1c1ce175a98101148495c031a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b3dcbe1c322f00ad1b384aefa22e55d427f5947977fc070055be8e8bd737e79a54fc1a198fd9d74d85a10630b369de0a1c8423df741391a65543b73790a8116", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e690332b8f6c176c92a10919909356adea871e37b0c049e188c65188e1e8f270179dc63eec5c3ba19554cae2af819a3b626c30f3e794894a555797b3d7dc6af"} build_url:https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011811 build_url: 'https://queue.taskcluster.net/v1/task/TyQolOxwSt2YyOpJ2eCEJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdc1bfaa35179a875b7f49874837197e0b76ee3ad2b2fa722663d69c1484a4e1bb79ef37dba11df8d325f62d34079c9812c7566c0a1955484cdfac78cc3225f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10cacdcc56465a54e83c2bdf4c1a2c683fef2b97774184984134321a215ae60e817ddac307769c33811a632ba3ba0e02b01e90b1c1ce175a98101148495c031a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b3dcbe1c322f00ad1b384aefa22e55d427f5947977fc070055be8e8bd737e79a54fc1a198fd9d74d85a10630b369de0a1c8423df741391a65543b73790a8116", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e690332b8f6c176c92a10919909356adea871e37b0c049e188c65188e1e8f270179dc63eec5c3ba19554cae2af819a3b626c30f3e794894a555797b3d7dc6af"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-24 21:28:35.440807) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 21:28:35.441289) ========= 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-1482640188.252823-602346170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004442 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 21:28:35.528925) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-24 21:28:35.529211) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-24 21:28:35.529473) ========= ========= Total master_lag: 0.19 =========