builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-040 starttime: 1477737418.62 results: success (0) buildid: 20161029004015 builduid: 9320a8fe4036463a9b063e86b23cff0f revision: 0c44c99f7b57f8827429eb3634c406bfea2115a9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 03:36:58.616048) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 03:36:58.616630) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 03:36:58.616943) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 03:36:58.637600) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 03:36:58.637996) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-29 03:36:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-10-29 03:36:59 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127502 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 03:36:58.788458) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 03:36:58.788804) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018440 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 03:36:58.845769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 03:36:58.846104) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0c44c99f7b57f8827429eb3634c406bfea2115a9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0c44c99f7b57f8827429eb3634c406bfea2115a9 --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=9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-29 03:36:59,452 truncating revision to first 12 chars 2016-10-29 03:36:59,452 Setting DEBUG logging. 2016-10-29 03:36:59,452 attempt 1/10 2016-10-29 03:36:59,452 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0c44c99f7b57?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-29 03:37:00,323 unpacking tar archive at: mozilla-aurora-0c44c99f7b57/testing/mozharness/ program finished with exit code 0 elapsedTime=1.267039 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 03:37:00.135893) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 03:37:00.136219) ========= script_repo_revision: 0c44c99f7b57f8827429eb3634c406bfea2115a9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 03:37:00.136644) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 03:37:00.136921) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 03:37:00.147655) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-10-29 03:37:00.148058) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:37:00 INFO - MultiFileLogger online at 20161029 03:37:00 in /builds/slave/test 03:37:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:37:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:37:00 INFO - {'append_to_log': False, 03:37:00 INFO - 'base_work_dir': '/builds/slave/test', 03:37:00 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:37:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:37:00 INFO - 'branch': 'Mozilla-Aurora', 03:37:00 INFO - 'buildbot_json_path': 'buildprops.json', 03:37:00 INFO - 'config_files': ('talos/linux_config.py',), 03:37:00 INFO - 'default_actions': ('clobber', 03:37:00 INFO - 'read-buildbot-config', 03:37:00 INFO - 'download-and-extract', 03:37:00 INFO - 'populate-webroot', 03:37:00 INFO - 'create-virtualenv', 03:37:00 INFO - 'install', 03:37:00 INFO - 'run-tests'), 03:37:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:37:00 INFO - 'download_minidump_stackwalk': True, 03:37:00 INFO - 'download_symbols': 'ondemand', 03:37:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:37:00 INFO - 'tooltool.py': '/tools/tooltool.py', 03:37:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:37:00 INFO - '/tools/misc-python/virtualenv.py')}, 03:37:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:37:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:37:00 INFO - 'installer_path': 'installer.exe', 03:37:00 INFO - 'log_level': 'info', 03:37:00 INFO - 'log_name': 'talos', 03:37:00 INFO - 'log_to_console': True, 03:37:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:37:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:37:00 INFO - 'opt_config_files': (), 03:37:00 INFO - 'pip_index': False, 03:37:00 INFO - 'sps_profile': False, 03:37:00 INFO - 'sps_profile_interval': 0, 03:37:00 INFO - 'suite': 'dromaeojs', 03:37:00 INFO - 'system_bits': '32', 03:37:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:37:00 INFO - 'title': 'talos-linux64-ix-040', 03:37:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:37:00 INFO - 'use_talos_json': True, 03:37:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:37:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:37:00 INFO - 'work_dir': 'build'} 03:37:00 INFO - [mozharness: 2016-10-29 10:37:00.845753Z] Running clobber step. 03:37:00 INFO - Running pre-action listener: _resource_record_pre_action 03:37:00 INFO - Running main action method: clobber 03:37:00 INFO - rmtree: /builds/slave/test/build 03:37:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:37:01 INFO - Running post-action listener: _resource_record_post_action 03:37:01 INFO - [mozharness: 2016-10-29 10:37:01.820677Z] Finished clobber step (success) 03:37:01 INFO - [mozharness: 2016-10-29 10:37:01.820778Z] Running read-buildbot-config step. 03:37:01 INFO - Running pre-action listener: _resource_record_pre_action 03:37:01 INFO - Running main action method: read_buildbot_config 03:37:01 INFO - Using buildbot properties: 03:37:01 INFO - { 03:37:01 INFO - "project": "", 03:37:01 INFO - "product": "firefox", 03:37:01 INFO - "script_repo_revision": "production", 03:37:01 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:37:01 INFO - "repository": "", 03:37:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:37:01 INFO - "buildid": "20161029004015", 03:37:01 INFO - "stage_platform": "linux64-pgo", 03:37:01 INFO - "basedir": "/builds/slave/test", 03:37:01 INFO - "nightly_build": "True", 03:37:01 INFO - "buildnumber": 562, 03:37:01 INFO - "slavename": "talos-linux64-ix-040", 03:37:01 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:37:01 INFO - "platform": "ubuntu64_hw", 03:37:01 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:37:01 INFO - "branch": "mozilla-aurora", 03:37:01 INFO - "repo_path": "releases/mozilla-aurora", 03:37:01 INFO - "revision": "0c44c99f7b57f8827429eb3634c406bfea2115a9", 03:37:01 INFO - "pgo_build": "True", 03:37:01 INFO - "builduid": "9320a8fe4036463a9b063e86b23cff0f", 03:37:01 INFO - "slavebuilddir": "test" 03:37:01 INFO - } 03:37:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 03:37:01 INFO - Running post-action listener: _resource_record_post_action 03:37:01 INFO - [mozharness: 2016-10-29 10:37:01.823334Z] Finished read-buildbot-config step (success) 03:37:01 INFO - [mozharness: 2016-10-29 10:37:01.823430Z] Running download-and-extract step. 03:37:01 INFO - Running pre-action listener: _resource_record_pre_action 03:37:01 INFO - Running main action method: download_and_extract 03:37:01 INFO - mkdir: /builds/slave/test/build/tests 03:37:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:01 INFO - trying https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:37:01 INFO - Downloading https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:37:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:37:02 INFO - Downloaded 1361 bytes. 03:37:02 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:37:02 INFO - Using the following test package requirements: 03:37:02 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:37:02 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:02 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:37:02 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:02 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:37:02 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:02 INFO - u'jsshell-linux-x86_64.zip'], 03:37:02 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:02 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:37:02 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:37:02 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:02 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:37:02 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:02 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:37:02 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:02 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:37:02 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:02 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:37:02 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:37:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:37:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:37:02 INFO - Fetch https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:37:03 INFO - Expected file size: 21895223 03:37:07 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:37:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:37:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:37:07 INFO - Fetch https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:37:08 INFO - Expected file size: 21895223 03:37:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 03:37:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:37:12 INFO - Fetch https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 03:37:14 INFO - Expected file size: 14414081 03:37: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')]} 03:37:15 INFO - trying https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:37:15 INFO - Downloading https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:37:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:37:17 INFO - Downloaded 63990121 bytes. 03:37:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:37:17 INFO - mkdir: /builds/slave/test/properties 03:37:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:37:17 INFO - Writing to file /builds/slave/test/properties/build_url 03:37:17 INFO - Contents: 03:37:17 INFO - build_url:https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:37:18 INFO - Running post-action listener: _resource_record_post_action 03:37:18 INFO - Running post-action listener: set_extra_try_arguments 03:37:18 INFO - [mozharness: 2016-10-29 10:37:18.395425Z] Finished download-and-extract step (success) 03:37:18 INFO - [mozharness: 2016-10-29 10:37:18.395655Z] Running populate-webroot step. 03:37:18 INFO - Running pre-action listener: _resource_record_pre_action 03:37:18 INFO - Running main action method: populate_webroot 03:37:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:37:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:37:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:37:18 INFO - '--tppagecycles', 03:37:18 INFO - '7'], 03:37:18 INFO - 'tests': ['tsvgm']}}, 03:37:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:37:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:37:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:37:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:37:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:37:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:37:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:18 INFO - 'talos_options': ['--disable-e10s'], 03:37:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:37:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:37:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:18 INFO - 'talos_options': ['--disable-e10s'], 03:37:18 INFO - 'tests': ['damp', 'tps']}, 03:37:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:18 INFO - 'tests': ['damp', 'tps']}, 03:37:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:37:18 INFO - 'tests': ['dromaeo_dom']}, 03:37:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:37:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:37:18 INFO - 'tests': ['basic_compositor_video']}, 03:37:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:37:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:37:18 INFO - 'tests': ['a11yr', 03:37:18 INFO - 'ts_paint', 03:37:18 INFO - 'tpaint', 03:37:18 INFO - 'sessionrestore', 03:37:18 INFO - 'sessionrestore_no_auto_restore', 03:37:18 INFO - 'tabpaint']}, 03:37:18 INFO - 'other-e10s': {'tests': ['a11yr', 03:37:18 INFO - 'ts_paint', 03:37:18 INFO - 'tpaint', 03:37:18 INFO - 'sessionrestore', 03:37:18 INFO - 'sessionrestore_no_auto_restore', 03:37:18 INFO - 'tabpaint']}, 03:37:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:37:18 INFO - 'ts_paint', 03:37:18 INFO - 'tpaint', 03:37:18 INFO - 'sessionrestore', 03:37:18 INFO - 'sessionrestore_no_auto_restore', 03:37:18 INFO - 'tabpaint']}, 03:37:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:37:18 INFO - 'ts_paint', 03:37:18 INFO - 'tpaint', 03:37:18 INFO - 'sessionrestore', 03:37:18 INFO - 'sessionrestore_no_auto_restore', 03:37:18 INFO - 'tabpaint']}, 03:37:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:37:18 INFO - 'tests': ['a11yr', 03:37:18 INFO - 'ts_paint', 03:37:18 INFO - 'tpaint', 03:37:18 INFO - 'sessionrestore', 03:37:18 INFO - 'sessionrestore_no_auto_restore', 03:37:18 INFO - 'tabpaint']}, 03:37:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:37:18 INFO - 'tests': ['a11yr', 03:37:18 INFO - 'ts_paint', 03:37:18 INFO - 'tpaint', 03:37:18 INFO - 'sessionrestore', 03:37:18 INFO - 'sessionrestore_no_auto_restore', 03:37:18 INFO - 'tabpaint']}, 03:37:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:37:18 INFO - 'tests': ['tsvgx', 03:37:18 INFO - 'tsvgr_opacity', 03:37:18 INFO - 'tart', 03:37:18 INFO - 'tscrollx', 03:37:18 INFO - 'cart']}, 03:37:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:37:18 INFO - 'tsvgr_opacity', 03:37:18 INFO - 'tart', 03:37:18 INFO - 'tscrollx', 03:37:18 INFO - 'cart']}, 03:37:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:18 INFO - 'talos_options': ['--disable-e10s'], 03:37:18 INFO - 'tests': ['tp5o']}, 03:37:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:18 INFO - 'tests': ['tp5o']}, 03:37:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:18 INFO - 'talos_options': ['--disable-e10s', 03:37:18 INFO - '--xperf_path', 03:37:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:37:18 INFO - 'tests': ['tp5n']}, 03:37:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:37:18 INFO - 'talos_options': ['--xperf_path', 03:37:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:37:18 INFO - 'tests': ['tp5n']}}, 03:37:18 INFO - 'talos.zip': {'path': '', 03:37:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:37:18 INFO - Running post-action listener: _resource_record_post_action 03:37:18 INFO - [mozharness: 2016-10-29 10:37:18.411452Z] Finished populate-webroot step (success) 03:37:18 INFO - [mozharness: 2016-10-29 10:37:18.411541Z] Running create-virtualenv step. 03:37:18 INFO - Running pre-action listener: _resource_record_pre_action 03:37:18 INFO - Running main action method: create_virtualenv 03:37:18 INFO - Creating virtualenv /builds/slave/test/build/venv 03:37:18 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 03:37:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:37:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:37:18 INFO - Using real prefix '/usr' 03:37:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:37:19 INFO - Installing distribute.............................................................................................................................................................................................done. 03:37:20 INFO - Installing pip.................done. 03:37:20 INFO - Return code: 0 03:37:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:37: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')]} 03:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37: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')]} 03:37:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f673514cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eff60>, '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 0x27f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27f11d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', '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 03:37: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', 'pip>=1.5'] in /builds/slave/test/build 03:37: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 pip>=1.5 03:37:20 INFO - Using env: {'DISPLAY': ':0', 03:37:20 INFO - 'HOME': '/home/cltbld', 03:37:20 INFO - 'LANG': 'en_US.UTF-8', 03:37:20 INFO - 'LANGUAGE': 'en_US:en', 03:37:20 INFO - 'LOGNAME': 'cltbld', 03:37:20 INFO - 'MAIL': '/var/mail/cltbld', 03:37:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:20 INFO - 'MOZ_NO_REMOTE': '1', 03:37:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:20 INFO - 'NO_EM_RESTART': '1', 03:37:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:20 INFO - 'PWD': '/builds/slave/test', 03:37:21 INFO - 'SHELL': '/bin/bash', 03:37:21 INFO - 'SHLVL': '1', 03:37:21 INFO - 'TERM': 'linux', 03:37:21 INFO - 'TMOUT': '86400', 03:37:21 INFO - 'USER': 'cltbld', 03:37:21 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', 03:37:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:21 INFO - '_': '/tools/buildbot/bin/python'} 03:37:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:21 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 03:37:21 INFO - Cleaning up... 03:37:21 INFO - Return code: 0 03:37:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:37:21 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')]} 03:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:21 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')]} 03:37:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:21 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 0x7f673514cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eff60>, '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 0x27f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27f11d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', '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 03:37:21 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 03:37:21 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 03:37:21 INFO - Using env: {'DISPLAY': ':0', 03:37:21 INFO - 'HOME': '/home/cltbld', 03:37:21 INFO - 'LANG': 'en_US.UTF-8', 03:37:21 INFO - 'LANGUAGE': 'en_US:en', 03:37:21 INFO - 'LOGNAME': 'cltbld', 03:37:21 INFO - 'MAIL': '/var/mail/cltbld', 03:37:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:21 INFO - 'MOZ_NO_REMOTE': '1', 03:37:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:21 INFO - 'NO_EM_RESTART': '1', 03:37:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:21 INFO - 'PWD': '/builds/slave/test', 03:37:21 INFO - 'SHELL': '/bin/bash', 03:37:21 INFO - 'SHLVL': '1', 03:37:21 INFO - 'TERM': 'linux', 03:37:21 INFO - 'TMOUT': '86400', 03:37:21 INFO - 'USER': 'cltbld', 03:37:21 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', 03:37:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:21 INFO - '_': '/tools/buildbot/bin/python'} 03:37:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:21 INFO - Downloading/unpacking psutil>=3.1.1 03:37: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 03:37: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 03:37: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 03:37: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 03:37: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 03:37: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 03:37:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:37:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:37:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:37:23 INFO - Installing collected packages: psutil 03:37:23 INFO - Running setup.py install for psutil 03:37:23 INFO - building 'psutil._psutil_linux' extension 03:37:23 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 03:37:23 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 03:37:23 INFO - building 'psutil._psutil_posix' extension 03:37:23 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 03:37:23 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 03:37:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:37:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:37:23 INFO - Successfully installed psutil 03:37:23 INFO - Cleaning up... 03:37:23 INFO - Return code: 0 03:37:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:37: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')]} 03:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37: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')]} 03:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f673514cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eff60>, '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 0x27f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27f11d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', '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 03:37: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:37: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 mozsystemmonitor==0.3 03:37:23 INFO - Using env: {'DISPLAY': ':0', 03:37:23 INFO - 'HOME': '/home/cltbld', 03:37:23 INFO - 'LANG': 'en_US.UTF-8', 03:37:23 INFO - 'LANGUAGE': 'en_US:en', 03:37:23 INFO - 'LOGNAME': 'cltbld', 03:37:23 INFO - 'MAIL': '/var/mail/cltbld', 03:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:23 INFO - 'MOZ_NO_REMOTE': '1', 03:37:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:23 INFO - 'NO_EM_RESTART': '1', 03:37:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:23 INFO - 'PWD': '/builds/slave/test', 03:37:24 INFO - 'SHELL': '/bin/bash', 03:37:24 INFO - 'SHLVL': '1', 03:37:24 INFO - 'TERM': 'linux', 03:37:24 INFO - 'TMOUT': '86400', 03:37:24 INFO - 'USER': 'cltbld', 03:37:24 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', 03:37:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:24 INFO - '_': '/tools/buildbot/bin/python'} 03:37:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:37:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:37:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:37:26 INFO - Installing collected packages: mozsystemmonitor 03:37:26 INFO - Running setup.py install for mozsystemmonitor 03:37:26 INFO - Successfully installed mozsystemmonitor 03:37:26 INFO - Cleaning up... 03:37:26 INFO - Return code: 0 03:37:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:26 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 0x7f673514cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eff60>, '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 0x27f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27f11d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', '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 03:37:26 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 03:37:26 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 03:37:26 INFO - Using env: {'DISPLAY': ':0', 03:37:26 INFO - 'HOME': '/home/cltbld', 03:37:26 INFO - 'LANG': 'en_US.UTF-8', 03:37:26 INFO - 'LANGUAGE': 'en_US:en', 03:37:26 INFO - 'LOGNAME': 'cltbld', 03:37:26 INFO - 'MAIL': '/var/mail/cltbld', 03:37:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:26 INFO - 'MOZ_NO_REMOTE': '1', 03:37:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:26 INFO - 'NO_EM_RESTART': '1', 03:37:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:26 INFO - 'PWD': '/builds/slave/test', 03:37:26 INFO - 'SHELL': '/bin/bash', 03:37:26 INFO - 'SHLVL': '1', 03:37:26 INFO - 'TERM': 'linux', 03:37:26 INFO - 'TMOUT': '86400', 03:37:26 INFO - 'USER': 'cltbld', 03:37:26 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', 03:37:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:26 INFO - '_': '/tools/buildbot/bin/python'} 03:37:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:26 INFO - Downloading/unpacking jsonschema==2.5.1 03:37: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 03:37: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 03:37: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 03:37: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 03:37: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 03:37: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 03:37:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:37:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:37:28 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 03:37:28 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 03:37:28 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 03:37:28 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 03:37:28 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 03:37:28 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 03:37:28 INFO - Downloading functools32-3.2.3-2.tar.gz 03:37:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:37:28 INFO - warning: no files found matching '*.txt' 03:37:28 INFO - no previously-included directories found matching 'build' 03:37:28 INFO - no previously-included directories found matching 'dist' 03:37:28 INFO - no previously-included directories found matching '.git*' 03:37:28 INFO - Installing collected packages: jsonschema, functools32 03:37:28 INFO - Running setup.py install for functools32 03:37:28 INFO - warning: no files found matching '*.txt' 03:37:28 INFO - no previously-included directories found matching 'build' 03:37:28 INFO - no previously-included directories found matching 'dist' 03:37:28 INFO - no previously-included directories found matching '.git*' 03:37:28 INFO - Successfully installed jsonschema functools32 03:37:28 INFO - Cleaning up... 03:37:28 INFO - Return code: 0 03:37:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:37:28 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')]} 03:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:28 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')]} 03:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:28 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 0x7f673514cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eff60>, '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 0x27f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27f11d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', '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 03:37:28 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 03:37:28 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 03:37:28 INFO - Using env: {'DISPLAY': ':0', 03:37:28 INFO - 'HOME': '/home/cltbld', 03:37:28 INFO - 'LANG': 'en_US.UTF-8', 03:37:28 INFO - 'LANGUAGE': 'en_US:en', 03:37:28 INFO - 'LOGNAME': 'cltbld', 03:37:28 INFO - 'MAIL': '/var/mail/cltbld', 03:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:28 INFO - 'MOZ_NO_REMOTE': '1', 03:37:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:28 INFO - 'NO_EM_RESTART': '1', 03:37:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:28 INFO - 'PWD': '/builds/slave/test', 03:37:28 INFO - 'SHELL': '/bin/bash', 03:37:28 INFO - 'SHLVL': '1', 03:37:28 INFO - 'TERM': 'linux', 03:37:28 INFO - 'TMOUT': '86400', 03:37:28 INFO - 'USER': 'cltbld', 03:37:28 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', 03:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:28 INFO - '_': '/tools/buildbot/bin/python'} 03:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:37:29 INFO - Cleaning up... 03:37:29 INFO - Return code: 0 03:37:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:37:29 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')]} 03:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:29 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')]} 03:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:29 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 0x7f673514cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eff60>, '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 0x27f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27f11d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', '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 03:37:29 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 03:37:29 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 03:37:29 INFO - Using env: {'DISPLAY': ':0', 03:37:29 INFO - 'HOME': '/home/cltbld', 03:37:29 INFO - 'LANG': 'en_US.UTF-8', 03:37:29 INFO - 'LANGUAGE': 'en_US:en', 03:37:29 INFO - 'LOGNAME': 'cltbld', 03:37:29 INFO - 'MAIL': '/var/mail/cltbld', 03:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:29 INFO - 'MOZ_NO_REMOTE': '1', 03:37:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:29 INFO - 'NO_EM_RESTART': '1', 03:37:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:29 INFO - 'PWD': '/builds/slave/test', 03:37:29 INFO - 'SHELL': '/bin/bash', 03:37:29 INFO - 'SHLVL': '1', 03:37:29 INFO - 'TERM': 'linux', 03:37:29 INFO - 'TMOUT': '86400', 03:37:29 INFO - 'USER': 'cltbld', 03:37:29 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', 03:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:29 INFO - '_': '/tools/buildbot/bin/python'} 03:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:29 INFO - Downloading/unpacking blobuploader==1.2.4 03:37:29 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 03:37:29 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 03:37:29 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 03:37:29 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 03:37:29 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 03:37:29 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 03:37:31 INFO - Downloading blobuploader-1.2.4.tar.gz 03:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:37:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:37:31 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 03:37:31 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 03:37:31 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 03:37:31 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 03:37:31 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 03:37:31 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 03:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:37:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:37:31 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 03:37:31 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 03:37:31 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 03:37:31 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 03:37:31 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 03:37:31 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 03:37:32 INFO - Downloading docopt-0.6.1.tar.gz 03:37:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:37:32 INFO - Installing collected packages: blobuploader, requests, docopt 03:37:32 INFO - Running setup.py install for blobuploader 03:37:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:37:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:37:32 INFO - Running setup.py install for requests 03:37:32 INFO - Running setup.py install for docopt 03:37:32 INFO - Successfully installed blobuploader requests docopt 03:37:32 INFO - Cleaning up... 03:37:32 INFO - Return code: 0 03:37:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:37:32 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')]} 03:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:32 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')]} 03:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:32 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 0x7f673514cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eff60>, '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 0x27f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27f11d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', '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 03:37:32 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 03:37:32 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 03:37:32 INFO - Using env: {'DISPLAY': ':0', 03:37:32 INFO - 'HOME': '/home/cltbld', 03:37:32 INFO - 'LANG': 'en_US.UTF-8', 03:37:32 INFO - 'LANGUAGE': 'en_US:en', 03:37:32 INFO - 'LOGNAME': 'cltbld', 03:37:32 INFO - 'MAIL': '/var/mail/cltbld', 03:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:32 INFO - 'MOZ_NO_REMOTE': '1', 03:37:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:32 INFO - 'NO_EM_RESTART': '1', 03:37:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:32 INFO - 'PWD': '/builds/slave/test', 03:37:32 INFO - 'SHELL': '/bin/bash', 03:37:32 INFO - 'SHLVL': '1', 03:37:32 INFO - 'TERM': 'linux', 03:37:32 INFO - 'TMOUT': '86400', 03:37:32 INFO - 'USER': 'cltbld', 03:37:32 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', 03:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:32 INFO - '_': '/tools/buildbot/bin/python'} 03:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:37:32 INFO - Running setup.py (path:/tmp/pip-WzJSOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:37:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:37:32 INFO - Running setup.py (path:/tmp/pip-pqsqfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:37:33 INFO - Running setup.py (path:/tmp/pip-wtejMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:37:33 INFO - Running setup.py (path:/tmp/pip-QSo8Wr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:37:33 INFO - Running setup.py (path:/tmp/pip-csYp0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:37:33 INFO - Running setup.py (path:/tmp/pip-82RG8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:37:33 INFO - Running setup.py (path:/tmp/pip-Po5g8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:37:33 INFO - Running setup.py (path:/tmp/pip-TobakA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:37:33 INFO - Running setup.py (path:/tmp/pip-xlFNln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:37:33 INFO - Running setup.py (path:/tmp/pip-5UED63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:37:33 INFO - Running setup.py (path:/tmp/pip-rCFIY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:37:33 INFO - Running setup.py (path:/tmp/pip-tHcDLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:37:33 INFO - Running setup.py (path:/tmp/pip-pAsvyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:37:33 INFO - Running setup.py (path:/tmp/pip-AiA1bg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:33 INFO - Running setup.py (path:/tmp/pip-CPZkQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:37:33 INFO - Running setup.py (path:/tmp/pip-cs3Qkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:37:34 INFO - Running setup.py (path:/tmp/pip-CPTCBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:37:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:37:34 INFO - Running setup.py install for manifestparser 03:37:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:37:34 INFO - Running setup.py install for mozcrash 03:37:34 INFO - Running setup.py install for mozdebug 03:37:34 INFO - Running setup.py install for mozdevice 03:37:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:37:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:37:34 INFO - Running setup.py install for mozfile 03:37:34 INFO - Running setup.py install for mozhttpd 03:37:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:37:34 INFO - Running setup.py install for mozinfo 03:37:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:37:34 INFO - Running setup.py install for mozInstall 03:37:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:37:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:37:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:37:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:37:34 INFO - Running setup.py install for mozleak 03:37:35 INFO - Running setup.py install for mozlog 03:37:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:37:35 INFO - Running setup.py install for moznetwork 03:37:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:37:35 INFO - Running setup.py install for mozprocess 03:37:35 INFO - Running setup.py install for mozprofile 03:37:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:37:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:37:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:37:35 INFO - Running setup.py install for mozrunner 03:37:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:37:35 INFO - Running setup.py install for mozscreenshot 03:37:35 INFO - Running setup.py install for moztest 03:37:35 INFO - Running setup.py install for mozversion 03:37:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:37:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:37:35 INFO - Cleaning up... 03:37:35 INFO - Return code: 0 03:37:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:35 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 0x7f673514cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eff60>, '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 0x27f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27f11d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', '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 03:37:35 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 03:37:35 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 03:37:35 INFO - Using env: {'DISPLAY': ':0', 03:37:35 INFO - 'HOME': '/home/cltbld', 03:37:35 INFO - 'LANG': 'en_US.UTF-8', 03:37:35 INFO - 'LANGUAGE': 'en_US:en', 03:37:35 INFO - 'LOGNAME': 'cltbld', 03:37:35 INFO - 'MAIL': '/var/mail/cltbld', 03:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:35 INFO - 'MOZ_NO_REMOTE': '1', 03:37:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:35 INFO - 'NO_EM_RESTART': '1', 03:37:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:35 INFO - 'PWD': '/builds/slave/test', 03:37:35 INFO - 'SHELL': '/bin/bash', 03:37:35 INFO - 'SHLVL': '1', 03:37:35 INFO - 'TERM': 'linux', 03:37:35 INFO - 'TMOUT': '86400', 03:37:35 INFO - 'USER': 'cltbld', 03:37:35 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', 03:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:35 INFO - '_': '/tools/buildbot/bin/python'} 03:37:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:37:36 INFO - Running setup.py (path:/tmp/pip-YOhiyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:37:36 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)) 03:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:37:36 INFO - Running setup.py (path:/tmp/pip-F3I_9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:37:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:37:36 INFO - Running setup.py (path:/tmp/pip-NLw6Gm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:37:36 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)) 03:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:37:36 INFO - Running setup.py (path:/tmp/pip-OZU1S8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:37:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:37:36 INFO - Running setup.py (path:/tmp/pip-kRmUrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:37:36 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)) 03:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:37:36 INFO - Running setup.py (path:/tmp/pip-85cE6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:37:36 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)) 03:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:37:36 INFO - Running setup.py (path:/tmp/pip-YnZxXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:37:36 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)) 03:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:37:36 INFO - Running setup.py (path:/tmp/pip-9xqHRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:37:36 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)) 03:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:37:36 INFO - Running setup.py (path:/tmp/pip-OvzSK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:37:36 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)) 03:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:37:36 INFO - Running setup.py (path:/tmp/pip-AFWLgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:37:36 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)) 03:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:37:36 INFO - Running setup.py (path:/tmp/pip-0L6CwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:37:36 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)) 03:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:37:36 INFO - Running setup.py (path:/tmp/pip-AN4KHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:37:37 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)) 03:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:37:37 INFO - Running setup.py (path:/tmp/pip-FNBVBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:37:37 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)) 03:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:37:37 INFO - Running setup.py (path:/tmp/pip-ovj1u3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:37:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:37 INFO - Running setup.py (path:/tmp/pip-lalrK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:37:37 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)) 03:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:37:37 INFO - Running setup.py (path:/tmp/pip-eb0xFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:37:37 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)) 03:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:37:37 INFO - Running setup.py (path:/tmp/pip-1NKJOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:37:37 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)) 03:37:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:37:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:37:37 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)) 03:37:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:37:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:37:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:37:37 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 03:37:37 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 03:37:37 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 03:37:37 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 03:37:37 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 03:37:37 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 03:37:39 INFO - Downloading blessings-1.6.tar.gz 03:37:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:37:39 INFO - Installing collected packages: blessings 03:37:39 INFO - Running setup.py install for blessings 03:37:39 INFO - Successfully installed blessings 03:37:39 INFO - Cleaning up... 03:37:39 INFO - Return code: 0 03:37:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:37:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:37:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:37:39 INFO - Reading from file tmpfile_stdout 03:37:39 INFO - Current package versions: 03:37:39 INFO - argparse == 1.2.1 03:37:39 INFO - blessings == 1.6 03:37:39 INFO - blobuploader == 1.2.4 03:37:39 INFO - docopt == 0.6.1 03:37:39 INFO - functools32 == 3.2.3-2 03:37:39 INFO - jsonschema == 2.5.1 03:37:39 INFO - manifestparser == 1.1 03:37:39 INFO - mozInstall == 1.12 03:37:39 INFO - mozcrash == 0.17 03:37:39 INFO - mozdebug == 0.1 03:37:39 INFO - mozdevice == 0.48 03:37:39 INFO - mozfile == 1.2 03:37:39 INFO - mozhttpd == 0.7 03:37:39 INFO - mozinfo == 0.9 03:37:39 INFO - mozleak == 0.1 03:37:39 INFO - mozlog == 3.4 03:37:39 INFO - moznetwork == 0.27 03:37:39 INFO - mozprocess == 0.23 03:37:39 INFO - mozprofile == 0.28 03:37:39 INFO - mozrunner == 6.12 03:37:39 INFO - mozscreenshot == 0.1 03:37:39 INFO - mozsystemmonitor == 0.3 03:37:39 INFO - moztest == 0.7 03:37:39 INFO - mozversion == 1.4 03:37:39 INFO - psutil == 3.1.1 03:37:39 INFO - requests == 1.2.3 03:37:39 INFO - wsgiref == 0.1.2 03:37:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:37:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:39 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 0x7f673514cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eff60>, '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 0x27f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27f11d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', '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 03:37:39 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 03:37:39 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 03:37:39 INFO - Using env: {'DISPLAY': ':0', 03:37:39 INFO - 'HOME': '/home/cltbld', 03:37:39 INFO - 'LANG': 'en_US.UTF-8', 03:37:39 INFO - 'LANGUAGE': 'en_US:en', 03:37:39 INFO - 'LOGNAME': 'cltbld', 03:37:39 INFO - 'MAIL': '/var/mail/cltbld', 03:37:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:39 INFO - 'MOZ_NO_REMOTE': '1', 03:37:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:39 INFO - 'NO_EM_RESTART': '1', 03:37:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:39 INFO - 'PWD': '/builds/slave/test', 03:37:39 INFO - 'SHELL': '/bin/bash', 03:37:39 INFO - 'SHLVL': '1', 03:37:39 INFO - 'TERM': 'linux', 03:37:39 INFO - 'TMOUT': '86400', 03:37:39 INFO - 'USER': 'cltbld', 03:37:39 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', 03:37:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:39 INFO - '_': '/tools/buildbot/bin/python'} 03:37:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:39 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)) 03:37:39 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)) 03:37:39 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)) 03:37:39 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)) 03:37:39 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)) 03:37:39 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)) 03:37:39 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)) 03:37:39 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)) 03:37:39 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)) 03:37:39 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)) 03:37:39 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)) 03:37:39 INFO - Cleaning up... 03:37:40 INFO - Return code: 0 03:37:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:37:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:37:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f673514cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eff60>, '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 0x27f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27f11d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', '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 03:37:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:37:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:37:40 INFO - Using env: {'DISPLAY': ':0', 03:37:40 INFO - 'HOME': '/home/cltbld', 03:37:40 INFO - 'LANG': 'en_US.UTF-8', 03:37:40 INFO - 'LANGUAGE': 'en_US:en', 03:37:40 INFO - 'LOGNAME': 'cltbld', 03:37:40 INFO - 'MAIL': '/var/mail/cltbld', 03:37:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:40 INFO - 'MOZ_NO_REMOTE': '1', 03:37:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:40 INFO - 'NO_EM_RESTART': '1', 03:37:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:40 INFO - 'PWD': '/builds/slave/test', 03:37:40 INFO - 'SHELL': '/bin/bash', 03:37:40 INFO - 'SHLVL': '1', 03:37:40 INFO - 'TERM': 'linux', 03:37:40 INFO - 'TMOUT': '86400', 03:37:40 INFO - 'USER': 'cltbld', 03:37:40 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', 03:37:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:40 INFO - '_': '/tools/buildbot/bin/python'} 03:37:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:37:40 INFO - Cleaning up... 03:37:40 INFO - Return code: 0 03:37:40 INFO - Running post-action listener: _resource_record_post_action 03:37:40 INFO - Running post-action listener: _start_resource_monitoring 03:37:40 INFO - Starting resource monitoring. 03:37:40 INFO - [mozharness: 2016-10-29 10:37:40.255995Z] Finished create-virtualenv step (success) 03:37:40 INFO - [mozharness: 2016-10-29 10:37:40.256452Z] Running install step. 03:37:40 INFO - Running pre-action listener: _resource_record_pre_action 03:37:40 INFO - Running main action method: install 03:37:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:37:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:37:40 INFO - Reading from file tmpfile_stdout 03:37:40 INFO - Detecting whether we're running mozinstall >=1.0... 03:37:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:37:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:37:40 INFO - Reading from file tmpfile_stdout 03:37:40 INFO - Output received: 03:37:40 INFO - Usage: mozinstall [options] installer 03:37:40 INFO - Options: 03:37:40 INFO - -h, --help show this help message and exit 03:37:40 INFO - -d DEST, --destination=DEST 03:37:40 INFO - Directory to install application into. [default: 03:37:40 INFO - "/builds/slave/test"] 03:37:40 INFO - --app=APP Application being installed. [default: firefox] 03:37:40 INFO - mkdir: /builds/slave/test/build/application 03:37:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:37:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:37:56 INFO - Reading from file tmpfile_stdout 03:37:56 INFO - Output received: 03:37:56 INFO - /builds/slave/test/build/application/firefox/firefox 03:37:56 INFO - Running post-action listener: _resource_record_post_action 03:37:56 INFO - [mozharness: 2016-10-29 10:37:56.524340Z] Finished install step (success) 03:37:56 INFO - [mozharness: 2016-10-29 10:37:56.524582Z] Running run-tests step. 03:37:56 INFO - Running pre-action listener: _resource_record_pre_action 03:37:56 INFO - Running main action method: run_tests 03:37:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:37:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:37:56 INFO - Python 2.7.3 03:37:56 INFO - Return code: 0 03:37:56 INFO - grabbing minidump binary from tooltool 03:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:56 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 0x27eff60>, '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 0x27f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27f11d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:37:56 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 03:37:56 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 03:37:56 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 03:37:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:37:56 INFO - Return code: 0 03:37:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:37:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:37:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:37:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:37:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:37:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 03:37:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 03:37:56 INFO - Using env: {'DISPLAY': ':0', 03:37:56 INFO - 'HOME': '/home/cltbld', 03:37:56 INFO - 'LANG': 'en_US.UTF-8', 03:37:56 INFO - 'LANGUAGE': 'en_US:en', 03:37:56 INFO - 'LOGNAME': 'cltbld', 03:37:56 INFO - 'MAIL': '/var/mail/cltbld', 03:37:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:37:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:37:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:56 INFO - 'MOZ_NO_REMOTE': '1', 03:37:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:37:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:56 INFO - 'NO_EM_RESTART': '1', 03:37:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:56 INFO - 'PWD': '/builds/slave/test', 03:37:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:37:56 INFO - 'SHELL': '/bin/bash', 03:37:56 INFO - 'SHLVL': '1', 03:37:56 INFO - 'TERM': 'linux', 03:37:56 INFO - 'TMOUT': '86400', 03:37:56 INFO - 'USER': 'cltbld', 03:37:56 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508', 03:37:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:56 INFO - '_': '/tools/buildbot/bin/python'} 03:37:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 03:37:56 INFO - mozversion application_buildid: 20161029004015 03:37:56 INFO - mozversion application_changeset: 0c44c99f7b57f8827429eb3634c406bfea2115a9 03:37:56 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:37:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:37:56 INFO - mozversion application_name: Firefox 03:37:56 INFO - mozversion application_remotingname: firefox-dev 03:37:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:37:56 INFO - mozversion application_vendor: Mozilla 03:37:56 INFO - mozversion application_version: 51.0a2 03:37:56 INFO - mozversion platform_buildid: 20161029004015 03:37:56 INFO - mozversion platform_changeset: 0c44c99f7b57f8827429eb3634c406bfea2115a9 03:37:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:37:56 INFO - mozversion platform_version: 51.0a2 03:37:56 INFO - using testdate: 1477737476 03:37:56 INFO - actual date: 1477737476 03:37:56 INFO - starting webserver on 'localhost:47305' 03:37:56 INFO - SUITE-START | Running 2 tests 03:37:56 INFO - TEST-START | dromaeo_css 03:37:56 INFO - operating with platform_type : linux_ 03:37:56 INFO - Initialising browser for dromaeo_css test... 03:37:56 INFO - TEST-INFO | started process 2790 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0On7Yo/profile http://localhost:47305/getInfo.html) 03:38:00 INFO - PROCESS | 2790 | __metrics Screen width/height:1600/1200 03:38:00 INFO - PROCESS | 2790 | colorDepth:24 03:38:00 INFO - PROCESS | 2790 | Browser inner width/height: 1024/697 03:38:00 INFO - PROCESS | 2790 | __metrics 03:38:01 INFO - PROCESS | 2790 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:38:01 INFO - PROCESS | 2790 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:38:02 INFO - PROCESS | 2790 | console.error: PushService: 03:38:02 INFO - PROCESS | 2790 | stateChangeProcessEnqueue: Error transitioning state 03:38:02 INFO - PROCESS | 2790 | UnknownError 03:38:02 INFO - TEST-INFO | 2790: exit 0 03:38:02 INFO - Browser initialized. 03:38:02 INFO - Running cycle 1/1 for dromaeo_css test... 03:38:02 INFO - TEST-INFO | started process 2929 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0On7Yo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:38:14 INFO - PROCESS | 2929 | RSS: Main: 144941056 03:38:14 INFO - PROCESS | 2929 | 03:39:05 INFO - PROCESS | 2929 | 1477737545795 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:41:15 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:47305/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47305/tests/dromaeo/cssquery-ext.html) 03:41:16 INFO - PROCESS | 2929 | RSS: Main: 236367872 03:41:16 INFO - PROCESS | 2929 | 03:44:18 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:47305/tests/dromaeo/cssquery-ext.html (next: http://localhost:47305/tests/dromaeo/cssquery-jquery.html) 03:44:19 INFO - PROCESS | 2929 | RSS: Main: 236908544 03:44:19 INFO - PROCESS | 2929 | 03:47:21 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:47305/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47305/tests/dromaeo/cssquery-mootools.html) 03:47:21 INFO - PROCESS | 2929 | RSS: Main: 227074048 03:47:21 INFO - PROCESS | 2929 | 03:50:23 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:47305/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47305/tests/dromaeo/cssquery-prototype.html) 03:50:24 INFO - PROCESS | 2929 | RSS: Main: 224186368 03:50:24 INFO - PROCESS | 2929 | 03:53:27 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:47305/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47305/tests/dromaeo/cssquery-yui.html) 03:53:27 INFO - PROCESS | 2929 | RSS: Main: 176525312 03:53:27 INFO - PROCESS | 2929 | 03:56:29 INFO - PROCESS | 2929 | Cycle 1(1): loaded http://localhost:47305/tests/dromaeo/cssquery-yui.html (next: http://localhost:47305/tests/dromaeo/cssquery-dojo.html) 03:56:30 INFO - PROCESS | 2929 | RSS: Main: 264028160 03:56:30 INFO - PROCESS | 2929 | 03:56:30 INFO - PROCESS | 2929 | __start_tp_report 03:56:30 INFO - PROCESS | 2929 | _x_x_mozilla_page_load 03:56:30 INFO - PROCESS | 2929 | _x_x_mozilla_page_load_details 03:56:30 INFO - PROCESS | 2929 | |i|pagename|runs| 03:56:30 INFO - PROCESS | 2929 | |0;dojo.html;3144.7641426892983;3204.8557814898327;3215.9196020099507;3239.8542065607044;3255.2024753935275;5552.805651802183;5670.432956704328;5694.54443644508;5703.173039909221;5705.229793977812;5651.9152212716735;5709.257796486461;5713.457221563945;5726.083826587746;5750.539956803456;5143.357080364945;5147.356580427447;5166.793328266865;5201.193814958688;5242.370915490127;5130.486951304858;5234.790608375661;5245.291885595453;5249.790008399659;5280.313559237294;5098.133317336044;5099.133147364957;5124.743762811863;5130.02529519392;5200.843974680765;5231.372235331765;5268.710220937847;5273.604479664041;5351.759170837322;5362.302900622933;5200.193969934666;5241.86895327616;5302.522772950414;5313.973430132824;5322.973385133049;5565.777368905239;5577.024020796344;5592.5805564582815;5613.045782217033;5620.241267428885;6011.368806275365;6019.668918209497;6119.571629985903;6122.418370254819;6126.264848218198;6125.540584456184;6161.630302181883;6168.907466387963;6174.413430724074;6180.505559555225;5571.303587051619;5574.888502229973;5597.356304025007;5610.943890561083;5610.943890561124;6133.86054778377;6161.630302181883;6166.753329866845;6167.259928808572;6185.690715464209;5947.6431414115295;5949.8215053548465;5950.077737950624;5951.5238780897425;5976.491998180203;5895.086261629454;5939.168516407706;5971.731272092767;5990.430909063632;6016.669083200379;6049.667268300285;6050.183225264621;6051.183090282755;6076.240469941257;6090.086487026892;6009.939900601025;6023.879522409527;6059.848503787441;6061.636301821904;6061.878762424727;4546.0680560485025;4582.289745089516;4638.512956139624;4672.088942656217;4672.2991551266905;4137.710360274812;4184.560621134798;4185.665146788249;4197.664186865043;4236.406903033578;5844.1818145459665;5860.531157507389;5861.296644402699;5862.062070068769;5867.207926929896;6011.519078473799;6033.698315084228;6168.938310616926;6169.96915015422;6179.598326108789;5770.653760774367;5832;5834.649921004753;5883.764649414061;5912.822615321547;5347.545458635982;5376.91934620981;5378.408375078739;5380.488853558905;5387.81142660005;6381.936180638227;6411.390917862889;6425.485961123006;6427.164468619143;6437.324080971524;5940.910886336622;5979.1031345296815;6033.155358249936;6076.483498902643;6088.117223002551;6327.209098862642;6339.524535659752;6348.968255158695;6356.809295721136;6357.268914074755;6012.458878700938;6030.095485677183;6030.517558595389;6033.034714445667;6056.121862329849;5893.6463812171405;5934.258217722785;5953.7023148843255;5972.462478376967;6073.240844894388;5928.318243401892;5931.733072011683;5940.405959404025;5947.316058653138;5982.700864956648;5900.527957763283;5932.199153114447;5933.050711886078;5935.851603709942;5942.762289508371;5738.8278351649515;5807.970960145341;5816.302043754777;5898.528117750484;5912.231409916855;5947.405259474018;5990.580659353804;6026.7287972040485;6034.517238620812;6044.455998960115;5662.377138514677;5869.06095024794;5907.232059832366;5919.940800591939;5921.644701317935 03:56:30 INFO - PROCESS | 2929 | |1;ext.html;591.3111225422383;613.4693490131037;641.4996302883752;647.36881540498;649.6134799794124;35918.46122308167;38762.22475550491;46789.999999999956;47453;47662;48827.51172488283;49199.26201106986;49311.260331095065;49370.753146234216;49445.25832112521;9261.212796912254;9420.340576159671;9421.293402994768;9431.622735090588;9435.103665151799;8528.530930798835;8745.562721863913;10881;11213.383263920481;11221.943890280538;11286.98417142457;11380.317180969168;11384.74767775544;11401.885981140165;11410.20128590999;8484.024337201177;11166.776664466706;11244.831327530093;11245.437728113644;11268.999999999958;8390.538520381377;10706.036456718934;11076;11080.722981925435;11425;45107;45107.548924510666;45465.31802022972;45477.54522454799;45494.54505454936;3484.860605575774;3598.442241452579;3782.583915769263;3944.23087497938;4239.8092085856215;34963;35158.824205879064;35239.647603523896;35260.118497037525;36687;4687.343771871941;4760.547747963972;4779.187538118528;4801.135795556798;4807.567318941312;263674;263682;265811;266329.67846125487;266399;39417.40873886894;40431.59568404337;62469.37530624727;62800;62883.68558157272;41558.58441415547;41901.580984189764;41987.37018944718;41998.58001420008;42003.78998105051;15567.065976041475;15612.531624051297;15619.60950976212;15625.765613515807;15637.68724625501;12370.876291236973;12621;12838.48646054166;12855.292958887165;12876.935615321863;9321.766955826022;9438.858417123749;9609.471479068718;9633.759156021155;9747.268954828416;3646.21606354635;3684.8157592120288;3685.7419980601635;3708.8887333380044;3721.14413684854;1051.8780696347185;1121.3888430805068;1160.4354376827139;1211.1340391620033;1229.170310040719;35096.47355289673;35424.29151417009;35779.28441431209;35833;35949.281014379565;1153.4405813180601;1154.509333533245;1184.6742145909943;1228.7419641875128;1244.570623135035;18194;18302.633947321247;18592.81407185938;18642.81357186438;18724.719129213074;547448;554174;555487;556296;556548;63308;63618.36381636124;63704;63713;63795.36204637894;1333.2000799520404;1382.1292585671165;1415.1084816565499;1431.921244331551;1444.6388402899274;33657.49513757246;35710.8214458926;36170.27659446744;36218;36280.4557931626;53204.73397632987;53764;53825.73087134696;53835.19247211217;53839;399127;399157;399970;400397;400618;49750;49816.50183498119;50158.49841501538;51067.744661276454;51613.741931290104;50091;51439.742801285756;54757.72621136869;55079.44920550743;55130.44869551253;44611.77694111509;46090.30864537102;46170;48005;49069.01861962812;45882.54117458916;46822;47442.52557474381;47840.282395763395;48136.27795582999;46186.30720539128;46305.768471157426;46403.76798115988;47055.52944470511;47107.764461177474;40215.1956960865;42354.364684530374;42402;42532.574674252864;43381.13237735164;30343.696563034086;30747.846260768554;30755;30789.846050769604;31159.376812464077 03:56:30 INFO - PROCESS | 2929 | |2;jquery.html;394.1742050404402;419.3269801967842;419.5175548119662;424.89377655586105;439.26203977318113;14733.189674567895;16546.172691365427;20338.288159914406;20482.48793780156;21893.343199704035;19441.902790486063;19717.309894153706;19786.901065494687;19938.900305498453;20440.28459003935;5786.3924287949685;5823.475887170154;5871.588988770788;5916.556258280646;5943.55423343251;4381.627561657263;5886.087656413263;5976.2230909981645;5977.701114944235;5978.671173085479;4686;6712.362325579063;6735.292794256606;6743.629100399476;6756.628385438799;5892.086726557369;6128.142060111588;6138.416850399205;6714.899276510856;6745.797626071226;6016.308124565688;6030.638161710312;6036.517078633743;6036.96981515094;6039.7282122304605;15333.846661533353;15403.22983850803;15599.1420471874;15633.687326253525;17530.824691753045;2430.4774473488283;2481.888315025828;2503.349129226396;2609.908653197148;3084.90745277642;10640.148788096934;11109.500072496758;11205.327680339205;11218.943905280503;11328.449437755185;2043.609613824474;2096.109153609725;2110.6939493773316;2115.0376578656674;2162.632352500063;67335.32664673388;70726.64636676836;73810.26189738087;82465.58767206185;90909;11947.761044779056;11980.341081240615;12148.635540933787;12320.137590368588;12329.383530823423;17689.734653980202;17704.203310851044;17706.29174833018;17716.379926702502;17723.202455889517;8519.57402129891;8607.526586037828;8649.1350864913;8654.134586541295;9031.45811251327;8083.514989100555;8114.513129212266;8279.917200828035;8305.916940830635;8309.875351869727;5174.97412512935;5243.68537887728;5277.340332458442;5340.786368545292;5370.650907690988;2135.7437107547194;2139.1443422631073;2140.860844045163;2143.5284237468045;2189.788303836858;453.6053633339016;462.79405664478986;1013.0730381700808;1034.270839058462;1085.0885256384674;12824.422900969479;13135.868641313657;13541.255230962195;13598.38807253676;13788.103773254707;479.1642898596711;480.9615230781599;484.14306677181895;489.9461059283476;496.201116202913;7128.144622645212;7247.746328878464;7249.673764680602;7250.383717383976;7294.270572942663;555089;555515;555572;579100;581794;105435.47282263539;107546;107965;108535;110780;478.6505850728987;493.3709520361496;493.96295277854307;494.8911239527298;495.37335270882363;9787.902120978984;11628.53485860556;11964.58123965657;12230.44962976686;12362.876371236172;45255.32117018313;47893.5210647889;48415.75792121017;49453.75273123611;49601.50398495969;56384.71807640936;61843.6907815458;62049;62921.68539157458;64240;8899.955500222717;9320.207782338306;9366.016568260235;9491.38306010107;9578.760530986781;12562.811557826457;14671.413143474141;14709.485168019068;14734.557963261119;14808.555743327717;12096.153269271419;12126.939365303117;12265.080118990933;12265.57070502528;12862.614121576367;5642;7269.273072692688;9687.370320929118;11831.704207394883;12278.079144064051;7385.889211661937;12413.689657758267;12425.62723118308;12595.118341715992;13109;9460.479673618018;9498.572564234764;9541.618335266512;9682.515874206121;9736.75658108553;10779.407132607781;12436.81344779811;12472.625821225378;12584.244945303311;12612.558560450707 03:56:30 INFO - PROCESS | 2929 | |3;mootools.html;1536.3854458216713;1550.899191552549;1732.7314266288722;1789.105447276362;1837.660032893914;8638.524881131536;9232.584533695983;9238.214751746093;9244.630214791408;9250.074992500746;7755.689772409111;8104.149064348247;8598.269147122503;8994.595243214055;9000.45997240166;2480.751924807518;2533.1767175667887;2536.822422430431;2537.479816637585;2549.146036077907;2503;2586.0819409109777;2587.1979686297213;2589.8187126901125;2592.4296654735926;2542.211914306571;2550.477152183807;2588.1071030494545;2591.9740802592014;2592.948141037178;2507.7868381187623;2510.8619025953567;2513.8617376044217;2541.567933451318;2545.8217924745277;2453.0800949643885;2480.1071614218868;2489.116363690882;2492.0654754467073;2503.9749602503925;8748.69379571712;8811.295096392274;8823.205911467998;8823.294136469067;8844.955775221148;5477.534409575191;5611.186377975211;5614.775408983635;5615;5625.156226566007;3650.379435495972;3734.2531493701367;3794.4877441545314;3814.122751767109;3822.120912190212;1231.2243286729392;1235.0736947289533;1251.5494422008069;1286.6847622332589;1300.5123078845434;11448.484818183202;11843.940780296043;11872.52509899603;12106.636800895987;12192.634220973385;4963.947172488894;4973.850784476472;5017.498250175026;5047.974760126176;5121.769520371592;10748.677539673823;10797.028267455968;10804.297720648132;10816.567337306577;10816.67549973502;5625.353084395265;5637.830865074055;5641.576881733804;5644.774209031592;5650.4349565043985;4569.543045695404;4611.769411529477;5106.7191304477865;5134.486551344836;5182.818601348935;4380.4305440292555;4435.645148388121;4539.092181563635;4545.499995000548;4563.155816174019;1443;1475.4467074846932;1479.7336479433804;1493.081754720839;1502.353987785263;782.3271986091956;879.1735768377705;932.398602901125;994.1251698505414;996.8853581838182;6209.161763161916;6304.968475157595;6347.428731414103;6357.205349331333;6472.126262954535;1005.8139244239838;1049.1449468683152;1054.3673795722505;1064.9627263045757;1091.3397394576325;4480.775961201927;4506.616937560344;4610.677252592353;4614.700044497097;4710.411198600175;10948.726281843017;11301.378424186749;11303.547858085749;11410;11429.25709828875;10340.172786177087;10741.087007604441;10771.569137234423;11421.37182454973;11457.369844658457;7922.128565857751;8085.555294458862;8114.8782768258525;8127.959360203279;8131.349492040505;6108.175396321618;6194.101855230876;6211.751529938932;6339.144215530844;6441.066045423294;10639.521221545205;10665.933406659273;10762.54705614732;10785.730356741144;10807.405592692478;10258.38449692991;10891.346519208873;10891.509882055168;10908.78182436331;11020.779584408427;7137.857242855218;7469.327760501581;7502.324790768639;7726.7681969541;7869.606519674108;8047.798805029921;8410.074891761778;8428.662853485792;8500.31997440191;8504.617292221988;7633.732819351296;7785.377169826514;7861.921380786119;7925.08861480914;7938.24586664266;7662.693492260247;7745.690172393041;7776.455648104578;7851.528908265523;7877.409194310335;6956.2000369958105;6987.860242795217;7022.508424410446;7046.436285097282;7087.610181439864;5208.895822083613;5271.7891284349735;5342.1452567589;5349.57203423733;5466.371367292653;5246.871922536673;5431.701256430934;5433.239346491573;5435.755391007493;5566.582506312124 03:56:30 INFO - PROCESS | 2929 | |4;prototype.html;330.34426663073793;343.90198793343893;344.3732407019226;348.3051312631301;348.35728081689274;5567.248421463101;5573.944260557393;5612.943870561298;5623.325200975878;5641.887162256758;5180;5259.790887827112;5320.780927538842;5391.379991301002;5502.339719233698;4280.443542339492;4286.849960251392;4289.806958686867;4290.270653988814;4295.420118284039;4292.635126014293;4301.569843015689;4303.569643035703;4306.504751953518;4328.913421731563;4147.896302592444;4216.662666986649;4274.722143060691;4282.186384586934;4334.154839806239;4268.850590229343;4272.252355837716;4280.229558679436;4280.400743895857;4286.164197981395;3751.681107105872;3766.7739935603677;3767.9811600942094;3795.35478968576;3799.8670046548514;5242.034237161065;5369.301990741179;5403;5403.5136837684795;5431.619786614939;4850.860047888743;4862;4871.805127794884;4895.192293271606;4897.926531102036;5696.8860622787315;5715.599908006441;5719.142128680731;5722.313322401338;5744.626599271034;4031.29479216436;4059.106996460774;4073.6333729964445;4127.133302006582;4147.212029714375;5945.721669840962;5981.444824345658;5988.461038506555;5999.460048595603;6008.309044459856;4211.894702632398;4213.957860421357;4221.472315960505;4238.385434112037;4244.087521182961;4132.421460995463;4278.823323586039;4289.978550107229;4294.046208974909;4299.312110062438;4828.927566086511;4840.661153719206;4857.854264372075;4862.270659401047;4871.561559459595;5739.7704091836695;5743.913841292384;5768.538516918637;5769.249997500298;5783.84780294732;1391.1653008195;1391.547746982235;1431.7566013777682;1442.4807069454987;1454.7672372420361;945.2154711589365;967.7967626798311;1003.9719766072493;1005.8038682456997;1010.368519675203;5556.166575013699;5559.777608895599;5577.191307260425;5577.832665019975;5632.662040277596;5725.656460612377;5911.349751527328;5914.817036592699;5942.762289508371;5956.434138756811;5541.695206763667;5569.359523654751;5573.386927437979;5581.441855814467;5600.899662626515;3095.4307924422674;3103.8758449662214;3123.9843800781305;3124.7812653114065;3125.1718294651937;5884.705764711747;5946.643201407929;6006.849828754316;6015.826913751864;6039.48664363534;5918.928375068692;5972.492338151219;5976;5984.31180414258;6002.369751176064;5809.622374545641;6014.127951447016;6017.518598512109;6035.706431556068;6049.3950604939155;5863.355030946507;5883.823485295448;5888.735006924613;5909.793157239476;5911.497522710618;5819.738111784895;5823.242978412779;5836.620619659709;5882.470577648032;5920.69093053718;5817.476427121579;6106.877862442638;6128.846778830387;6148.354422785543;6165.5375846810775;4229.323308270662;4243.660507159481;4272.91454170921;4286.65706743466;4295.076558539929;5787.826365209051;6020.585162486725;6030.879382412415;6042.758289668364;6047.637141771508;5748.712564371848;5781.682007489629;5784.681842498703;5794.5364370851075;5796.188533605215;5783.103618939072;5789.739461724148;5792.247007889116;5807.0998995155815;5859.589828711949;5356.946430535645;5488.670679759227;5742.138679198154;5776.660452516738;5782.335031471434;5810.883782324246;5817.156512305607;5847.736851841592;5848.619839710405;5855.238818953509;5569.972150139223;5646.797748877859;5685.403032681509;5687.744051517774;5695.572832037697 03:56:30 INFO - PROCESS | 2929 | |5;yui.html;635.301168714414;666.1739443090567;697.5152269172924;762.8664983627865;805.472415567803;16165.676686466279;16833.074180920044;16891.83108168921;16894.915525422355;16899.74650380244;15349.769753453707;15387.99978001432;15431.45989890354;15446.459373921914;15448.691026179498;840.5503055865098;889.9332550058739;893.3166127912152;893.8078313162669;893.852514335134;884.9203571678547;887.2236792806294;887.7248053103558;888.7156110044788;888.8800011998394;937.9624815007392;938.8450905600567;939.7556635274809;940.1444685336345;940.9106134917206;935.2471260635186;935.2658163341778;935.4106912645058;935.5369092299287;936.6159874451477;684.7774473296199;688.1123350877363;688.3804575881696;688.4526801193064;688.5421194905382;16733.330666773316;16747;16762.32950681971;16788.496345109666;16793.66412671752;13892.23592702401;14152.433902643985;14251.429942802275;14545;14650.487232946902;11713.180077394585;11737.53049878004;12136.08979326554;12139.817902731465;12879.227246365155;4658.487566367697;4676.579107880307;4724.952750472486;4764.166270902613;4920.261960705922;80046.19953800504;82966.58516707378;83286.58356708178;84119.5794021026;84265;11329.716757080974;11345.886541134649;11351.751307356184;11380.715482112848;11473.598424055284;20813.375598732062;20846.270380536607;20863.269785557735;20864.165433382797;20868.26961056386;8758.124187581318;8813.427127236837;8873.245774109144;8884.73345799627;8885.822283554422;4847.3698419205275;4911.877203069952;4927.359443272351;5035.018171456531;5041.319421878073;3226.1934516370907;3332.766706330582;3337.0489410517885;3337.4493208620793;3349.062262566485;570.566369559138;578.3118089473597;586.9501092407107;592.1059200607115;600.342624825815;636.4208570201095;638.9808305750835;660.1550015979553;664.7872680742116;678.3996163395332;9187.035361287102;9226.35415520907;9350.483533912216;9399.483028433502;9501.287403444769;589.8764447987212;621.0373920423326;646.3052218864739;647.3688154049764;652.768267265114;4651.209294419957;4698.741569213657;4716.245400735866;4746.5490778376;4787.4494433139935;105335;132498;133749.33125334312;134020.32989835183;136029;94761.52619236997;111847.44076279567;112855.4357228225;113896.29155562673;115498;60778;68182;99752;102203;102819.48590257001;8549;8751.606177721891;8821.955890220508;8852.291816654524;8858.311974459242;58956.41043589509;59898.401015991025;60565.69717151386;61501;62085;80515.59742201252;87563.56218218867;91277;91531;91616;11000.889991099986;11051.447427628425;11162.38606876597;11182;11835.763284734428;11861.347625880659;12114.0308775296;12199.329036903055;12287.385630718607;12316.81524777147;10439.217058720475;10767.246292759432;10964.890351096707;11053.28153670009;11097;10900.607735627724;11130.721731956766;11347.943260283975;11405.087592992706;11920.32645946005;11340.489677964346;11341.716457088638;11563.479643415898;13205.867941320465;14150.080244784056;10952.945235274092;10977.83533246986;11387.886121139014;11716.297022178696;12107;11430.771384572095;11676.585368291366;11714.23857449263;11792.23350482216;11980.880191197977 03:56:30 INFO - PROCESS | 2929 | __end_tp_report 03:56:30 INFO - PROCESS | 2929 | __start_cc_report 03:56:30 INFO - PROCESS | 2929 | _x_x_mozilla_cycle_collect,874 03:56:30 INFO - PROCESS | 2929 | __end_cc_report 03:56:30 INFO - PROCESS | 2929 | __startTimestamp1477738590091__endTimestamp 03:56:30 INFO - PROCESS | 2929 | 03:56:30 INFO - PROCESS | 2929 | ------- Summary: start ------- 03:56:30 INFO - PROCESS | 2929 | Number of tests: 6 03:56:30 INFO - PROCESS | 2929 | 03:56:30 INFO - PROCESS | 2929 | [#0] dojo.html Cycles:165 Average:5656.94 Median:5900.53 stddev:643.02 (10.9%) stddev-sans-first:614.05 03:56:30 INFO - PROCESS | 2929 | Values: 3144.8 3204.9 3215.9 3239.9 3255.2 5552.8 5670.4 5694.5 5703.2 5705.2 5651.9 5709.3 5713.5 5726.1 5750.5 5143.4 5147.4 5166.8 5201.2 5242.4 5130.5 5234.8 5245.3 5249.8 5280.3 5098.1 5099.1 5124.7 5130.0 5200.8 5231.4 5268.7 5273.6 5351.8 5362.3 5200.2 5241.9 5302.5 5314.0 5323.0 5565.8 5577.0 5592.6 5613.0 5620.2 6011.4 6019.7 6119.6 6122.4 6126.3 6125.5 6161.6 6168.9 6174.4 6180.5 5571.3 5574.9 5597.4 5610.9 5610.9 6133.9 6161.6 6166.8 6167.3 6185.7 5947.6 5949.8 5950.1 5951.5 5976.5 5895.1 5939.2 5971.7 5990.4 6016.7 6049.7 6050.2 6051.2 6076.2 6090.1 6009.9 6023.9 6059.8 6061.6 6061.9 4546.1 4582.3 4638.5 4672.1 4672.3 4137.7 4184.6 4185.7 4197.7 4236.4 5844.2 5860.5 5861.3 5862.1 5867.2 6011.5 6033.7 6168.9 6170.0 6179.6 5770.7 5832.0 5834.6 5883.8 5912.8 5347.5 5376.9 5378.4 5380.5 5387.8 6381.9 6411.4 6425.5 6427.2 6437.3 5940.9 5979.1 6033.2 6076.5 6088.1 6327.2 6339.5 6349.0 6356.8 6357.3 6012.5 6030.1 6030.5 6033.0 6056.1 5893.6 5934.3 5953.7 5972.5 6073.2 5928.3 5931.7 5940.4 5947.3 5982.7 5900.5 5932.2 5933.1 5935.9 5942.8 5738.8 5808.0 5816.3 5898.5 5912.2 5947.4 5990.6 6026.7 6034.5 6044.5 5662.4 5869.1 5907.2 5919.9 5921.6 03:56:30 INFO - PROCESS | 2929 | 03:56:30 INFO - PROCESS | 2929 | [#1] ext.html Cycles:180 Average:58857.11 Median:41730.08 stddev:112168.93 (268.8%) stddev-sans-first:112398.28 03:56:30 INFO - PROCESS | 2929 | Values: 591.3 613.5 641.5 647.4 649.6 35918.5 38762.2 46790.0 47453.0 47662.0 48827.5 49199.3 49311.3 49370.8 49445.3 9261.2 9420.3 9421.3 9431.6 9435.1 8528.5 8745.6 10881.0 11213.4 11221.9 11287.0 11380.3 11384.7 11401.9 11410.2 8484.0 11166.8 11244.8 11245.4 11269.0 8390.5 10706.0 11076.0 11080.7 11425.0 45107.0 45107.5 45465.3 45477.5 45494.5 3484.9 3598.4 3782.6 3944.2 4239.8 34963.0 35158.8 35239.6 35260.1 36687.0 4687.3 4760.5 4779.2 4801.1 4807.6 263674.0 263682.0 265811.0 266329.7 266399.0 39417.4 40431.6 62469.4 62800.0 62883.7 41558.6 41901.6 41987.4 41998.6 42003.8 15567.1 15612.5 15619.6 15625.8 15637.7 12370.9 12621.0 12838.5 12855.3 12876.9 9321.8 9438.9 9609.5 9633.8 9747.3 3646.2 3684.8 3685.7 3708.9 3721.1 1051.9 1121.4 1160.4 1211.1 1229.2 35096.5 35424.3 35779.3 35833.0 35949.3 1153.4 1154.5 1184.7 1228.7 1244.6 18194.0 18302.6 18592.8 18642.8 18724.7 547448.0 554174.0 555487.0 556296.0 556548.0 63308.0 63618.4 63704.0 63713.0 63795.4 1333.2 1382.1 1415.1 1431.9 1444.6 33657.5 35710.8 36170.3 36218.0 36280.5 53204.7 53764.0 53825.7 53835.2 53839.0 399127.0 399157.0 399970.0 400397.0 400618.0 49750.0 49816.5 50158.5 51067.7 51613.7 50091.0 51439.7 54757.7 55079.4 55130.4 44611.8 46090.3 46170.0 48005.0 49069.0 45882.5 46822.0 47442.5 47840.3 48136.3 46186.3 46305.8 46403.8 47055.5 47107.8 40215.2 42354.4 42402.0 42532.6 43381.1 30343.7 30747.8 30755.0 30789.8 31159.4 03:56:30 INFO - PROCESS | 2929 | 03:56:30 INFO - PROCESS | 2929 | [#2] jquery.html Cycles:180 Average:31332.22 Median:458.20 stddev:93286.83 (20359.4%) stddev-sans-first:93519.60 03:56:30 INFO - PROCESS | 2929 | Values: 394.2 419.3 419.5 424.9 439.3 14733.2 16546.2 20338.3 20482.5 21893.3 19441.9 19717.3 19786.9 19938.9 20440.3 5786.4 5823.5 5871.6 5916.6 5943.6 4381.6 5886.1 5976.2 5977.7 5978.7 4686.0 6712.4 6735.3 6743.6 6756.6 5892.1 6128.1 6138.4 6714.9 6745.8 6016.3 6030.6 6036.5 6037.0 6039.7 15333.8 15403.2 15599.1 15633.7 17530.8 2430.5 2481.9 2503.3 2609.9 3084.9 10640.1 11109.5 11205.3 11218.9 11328.4 2043.6 2096.1 2110.7 2115.0 2162.6 67335.3 70726.6 73810.3 82465.6 90909.0 11947.8 11980.3 12148.6 12320.1 12329.4 17689.7 17704.2 17706.3 17716.4 17723.2 8519.6 8607.5 8649.1 8654.1 9031.5 8083.5 8114.5 8279.9 8305.9 8309.9 5175.0 5243.7 5277.3 5340.8 5370.7 2135.7 2139.1 2140.9 2143.5 2189.8 453.6 462.8 1013.1 1034.3 1085.1 12824.4 13135.9 13541.3 13598.4 13788.1 479.2 481.0 484.1 489.9 496.2 7128.1 7247.7 7249.7 7250.4 7294.3 555089.0 555515.0 555572.0 579100.0 581794.0 105435.5 107546.0 107965.0 108535.0 110780.0 478.7 493.4 494.0 494.9 495.4 9787.9 11628.5 11964.6 12230.4 12362.9 45255.3 47893.5 48415.8 49453.8 49601.5 56384.7 61843.7 62049.0 62921.7 64240.0 8900.0 9320.2 9366.0 9491.4 9578.8 12562.8 14671.4 14709.5 14734.6 14808.6 12096.2 12126.9 12265.1 12265.6 12862.6 5642.0 7269.3 9687.4 11831.7 12278.1 7385.9 12413.7 12425.6 12595.1 13109.0 9460.5 9498.6 9541.6 9682.5 9736.8 10779.4 12436.8 12472.6 12584.2 12612.6 03:56:30 INFO - PROCESS | 2929 | 03:56:30 INFO - PROCESS | 2929 | [#3] mootools.html Cycles:180 Average:5998.12 Median:4662.56 stddev:3277.58 (70.3%) stddev-sans-first:3269.62 03:56:30 INFO - PROCESS | 2929 | Values: 1536.4 1550.9 1732.7 1789.1 1837.7 8638.5 9232.6 9238.2 9244.6 9250.1 7755.7 8104.1 8598.3 8994.6 9000.5 2480.8 2533.2 2536.8 2537.5 2549.1 2503.0 2586.1 2587.2 2589.8 2592.4 2542.2 2550.5 2588.1 2592.0 2592.9 2507.8 2510.9 2513.9 2541.6 2545.8 2453.1 2480.1 2489.1 2492.1 2504.0 8748.7 8811.3 8823.2 8823.3 8845.0 5477.5 5611.2 5614.8 5615.0 5625.2 3650.4 3734.3 3794.5 3814.1 3822.1 1231.2 1235.1 1251.5 1286.7 1300.5 11448.5 11843.9 11872.5 12106.6 12192.6 4963.9 4973.9 5017.5 5048.0 5121.8 10748.7 10797.0 10804.3 10816.6 10816.7 5625.4 5637.8 5641.6 5644.8 5650.4 4569.5 4611.8 5106.7 5134.5 5182.8 4380.4 4435.6 4539.1 4545.5 4563.2 1443.0 1475.4 1479.7 1493.1 1502.4 782.3 879.2 932.4 994.1 996.9 6209.2 6305.0 6347.4 6357.2 6472.1 1005.8 1049.1 1054.4 1065.0 1091.3 4480.8 4506.6 4610.7 4614.7 4710.4 10948.7 11301.4 11303.5 11410.0 11429.3 10340.2 10741.1 10771.6 11421.4 11457.4 7922.1 8085.6 8114.9 8128.0 8131.3 6108.2 6194.1 6211.8 6339.1 6441.1 10639.5 10665.9 10762.5 10785.7 10807.4 10258.4 10891.3 10891.5 10908.8 11020.8 7137.9 7469.3 7502.3 7726.8 7869.6 8047.8 8410.1 8428.7 8500.3 8504.6 7633.7 7785.4 7861.9 7925.1 7938.2 7662.7 7745.7 7776.5 7851.5 7877.4 6956.2 6987.9 7022.5 7046.4 7087.6 5208.9 5271.8 5342.1 5349.6 5466.4 5246.9 5431.7 5433.2 5435.8 5566.6 03:56:30 INFO - PROCESS | 2929 | 03:56:30 INFO - PROCESS | 2929 | [#4] prototype.html Cycles:180 Average:4849.98 Median:5571.68 stddev:1428.21 (25.6%) stddev-sans-first:1391.34 03:56:30 INFO - PROCESS | 2929 | Values: 330.3 343.9 344.4 348.3 348.4 5567.2 5573.9 5612.9 5623.3 5641.9 5180.0 5259.8 5320.8 5391.4 5502.3 4280.4 4286.8 4289.8 4290.3 4295.4 4292.6 4301.6 4303.6 4306.5 4328.9 4147.9 4216.7 4274.7 4282.2 4334.2 4268.9 4272.3 4280.2 4280.4 4286.2 3751.7 3766.8 3768.0 3795.4 3799.9 5242.0 5369.3 5403.0 5403.5 5431.6 4850.9 4862.0 4871.8 4895.2 4897.9 5696.9 5715.6 5719.1 5722.3 5744.6 4031.3 4059.1 4073.6 4127.1 4147.2 5945.7 5981.4 5988.5 5999.5 6008.3 4211.9 4214.0 4221.5 4238.4 4244.1 4132.4 4278.8 4290.0 4294.0 4299.3 4828.9 4840.7 4857.9 4862.3 4871.6 5739.8 5743.9 5768.5 5769.2 5783.8 1391.2 1391.5 1431.8 1442.5 1454.8 945.2 967.8 1004.0 1005.8 1010.4 5556.2 5559.8 5577.2 5577.8 5632.7 5725.7 5911.3 5914.8 5942.8 5956.4 5541.7 5569.4 5573.4 5581.4 5600.9 3095.4 3103.9 3124.0 3124.8 3125.2 5884.7 5946.6 6006.8 6015.8 6039.5 5918.9 5972.5 5976.0 5984.3 6002.4 5809.6 6014.1 6017.5 6035.7 6049.4 5863.4 5883.8 5888.7 5909.8 5911.5 5819.7 5823.2 5836.6 5882.5 5920.7 5817.5 6106.9 6128.8 6148.4 6165.5 4229.3 4243.7 4272.9 4286.7 4295.1 5787.8 6020.6 6030.9 6042.8 6047.6 5748.7 5781.7 5784.7 5794.5 5796.2 5783.1 5789.7 5792.2 5807.1 5859.6 5356.9 5488.7 5742.1 5776.7 5782.3 5810.9 5817.2 5847.7 5848.6 5855.2 5570.0 5646.8 5685.4 5687.7 5695.6 03:56:30 INFO - PROCESS | 2929 | 03:56:30 INFO - PROCESS | 2929 | [#5] yui.html Cycles:180 Average:22142.23 Median:4720.60 stddev:33556.07 (710.8%) stddev-sans-first:33611.35 03:56:30 INFO - PROCESS | 2929 | Values: 635.3 666.2 697.5 762.9 805.5 16165.7 16833.1 16891.8 16894.9 16899.7 15349.8 15388.0 15431.5 15446.5 15448.7 840.6 889.9 893.3 893.8 893.9 884.9 887.2 887.7 888.7 888.9 938.0 938.8 939.8 940.1 940.9 935.2 935.3 935.4 935.5 936.6 684.8 688.1 688.4 688.5 688.5 16733.3 16747.0 16762.3 16788.5 16793.7 13892.2 14152.4 14251.4 14545.0 14650.5 11713.2 11737.5 12136.1 12139.8 12879.2 4658.5 4676.6 4725.0 4764.2 4920.3 80046.2 82966.6 83286.6 84119.6 84265.0 11329.7 11345.9 11351.8 11380.7 11473.6 20813.4 20846.3 20863.3 20864.2 20868.3 8758.1 8813.4 8873.2 8884.7 8885.8 4847.4 4911.9 4927.4 5035.0 5041.3 3226.2 3332.8 3337.0 3337.4 3349.1 570.6 578.3 587.0 592.1 600.3 636.4 639.0 660.2 664.8 678.4 9187.0 9226.4 9350.5 9399.5 9501.3 589.9 621.0 646.3 647.4 652.8 4651.2 4698.7 4716.2 4746.5 4787.4 105335.0 132498.0 133749.3 134020.3 136029.0 94761.5 111847.4 112855.4 113896.3 115498.0 60778.0 68182.0 99752.0 102203.0 102819.5 8549.0 8751.6 8822.0 8852.3 8858.3 58956.4 59898.4 60565.7 61501.0 62085.0 80515.6 87563.6 91277.0 91531.0 91616.0 11000.9 11051.4 11162.4 11182.0 11835.8 11861.3 12114.0 12199.3 12287.4 12316.8 10439.2 10767.2 10964.9 11053.3 11097.0 10900.6 11130.7 11347.9 11405.1 11920.3 11340.5 11341.7 11563.5 13205.9 14150.1 10952.9 10977.8 11387.9 11716.3 12107.0 11430.8 11676.6 11714.2 11792.2 11980.9 03:56:30 INFO - PROCESS | 2929 | -------- Summary: end -------- 03:56:30 INFO - PROCESS | 2929 | 03:56:30 INFO - TEST-INFO | 2929: exit 0 03:56:30 INFO - TEST-OK | dromaeo_css | took 1113913ms 03:56:30 INFO - TEST-START | kraken 03:56:30 INFO - operating with platform_type : linux_ 03:56:30 INFO - Initialising browser for kraken test... 03:56:30 INFO - TEST-INFO | started process 3251 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfTq0lA/profile http://localhost:47305/getInfo.html) 03:56:34 INFO - PROCESS | 3251 | __metrics Screen width/height:1600/1200 03:56:34 INFO - PROCESS | 3251 | colorDepth:24 03:56:34 INFO - PROCESS | 3251 | Browser inner width/height: 1024/697 03:56:34 INFO - PROCESS | 3251 | __metrics 03:56:35 INFO - PROCESS | 3251 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:56:35 INFO - PROCESS | 3251 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:56:35 INFO - PROCESS | 3251 | console.error: PushService: 03:56:35 INFO - PROCESS | 3251 | stateChangeProcessEnqueue: Error transitioning state 03:56:35 INFO - PROCESS | 3251 | UnknownError 03:56:36 INFO - TEST-INFO | 3251: exit 0 03:56:36 INFO - Browser initialized. 03:56:36 INFO - Running cycle 1/1 for kraken test... 03:56:36 INFO - TEST-INFO | started process 3342 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfTq0lA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:56:48 INFO - PROCESS | 3342 | RSS: Main: 145309696 03:56:48 INFO - PROCESS | 3342 | 03:56:50 INFO - PROCESS | 3342 | Cycle 1(1): loaded http://localhost:47305/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47305/tests/kraken/driver.html?testName=audio-beat-detection) 03:56:51 INFO - PROCESS | 3342 | RSS: Main: 186089472 03:56:51 INFO - PROCESS | 3342 | 03:56:54 INFO - PROCESS | 3342 | Cycle 1(1): loaded http://localhost:47305/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47305/tests/kraken/driver.html?testName=audio-dft) 03:56:54 INFO - PROCESS | 3342 | RSS: Main: 175783936 03:56:54 INFO - PROCESS | 3342 | 03:56:57 INFO - PROCESS | 3342 | Cycle 1(1): loaded http://localhost:47305/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47305/tests/kraken/driver.html?testName=audio-fft) 03:56:58 INFO - PROCESS | 3342 | RSS: Main: 267526144 03:56:58 INFO - PROCESS | 3342 | 03:57:00 INFO - PROCESS | 3342 | Cycle 1(1): loaded http://localhost:47305/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47305/tests/kraken/driver.html?testName=audio-oscillator) 03:57:00 INFO - PROCESS | 3342 | RSS: Main: 173346816 03:57:00 INFO - PROCESS | 3342 | 03:57:02 INFO - PROCESS | 3342 | Cycle 1(1): loaded http://localhost:47305/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47305/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:57:03 INFO - PROCESS | 3342 | RSS: Main: 175964160 03:57:03 INFO - PROCESS | 3342 | 03:57:06 INFO - PROCESS | 3342 | Cycle 1(1): loaded http://localhost:47305/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47305/tests/kraken/driver.html?testName=imaging-darkroom) 03:57:07 INFO - PROCESS | 3342 | RSS: Main: 211353600 03:57:07 INFO - PROCESS | 3342 | 03:57:10 INFO - PROCESS | 3342 | Cycle 1(1): loaded http://localhost:47305/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47305/tests/kraken/driver.html?testName=imaging-desaturate) 03:57:10 INFO - PROCESS | 3342 | RSS: Main: 209731584 03:57:10 INFO - PROCESS | 3342 | 03:57:13 INFO - PROCESS | 3342 | Cycle 1(1): loaded http://localhost:47305/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47305/tests/kraken/driver.html?testName=json-parse-financial) 03:57:14 INFO - PROCESS | 3342 | RSS: Main: 210329600 03:57:14 INFO - PROCESS | 3342 | 03:57:15 INFO - PROCESS | 3342 | Cycle 1(1): loaded http://localhost:47305/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47305/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:57:16 INFO - PROCESS | 3342 | RSS: Main: 172347392 03:57:16 INFO - PROCESS | 3342 | 03:57:18 INFO - PROCESS | 3342 | Cycle 1(1): loaded http://localhost:47305/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47305/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:57:18 INFO - PROCESS | 3342 | RSS: Main: 179789824 03:57:18 INFO - PROCESS | 3342 | 03:57:20 INFO - PROCESS | 3342 | Cycle 1(1): loaded http://localhost:47305/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47305/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:57:21 INFO - PROCESS | 3342 | RSS: Main: 188833792 03:57:21 INFO - PROCESS | 3342 | 03:57:23 INFO - PROCESS | 3342 | Cycle 1(1): loaded http://localhost:47305/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47305/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:57:24 INFO - PROCESS | 3342 | RSS: Main: 197152768 03:57:24 INFO - PROCESS | 3342 | 03:57:27 INFO - PROCESS | 3342 | Cycle 1(1): loaded http://localhost:47305/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47305/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:57:27 INFO - PROCESS | 3342 | RSS: Main: 194125824 03:57:27 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | Cycle 1(1): loaded http://localhost:47305/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47305/tests/kraken/driver.html?testName=ai-astar) 03:57:29 INFO - PROCESS | 3342 | RSS: Main: 244215808 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | __start_tp_report 03:57:29 INFO - PROCESS | 3342 | _x_x_mozilla_page_load 03:57:29 INFO - PROCESS | 3342 | _x_x_mozilla_page_load_details 03:57:29 INFO - PROCESS | 3342 | |i|pagename|runs| 03:57:29 INFO - PROCESS | 3342 | |0;ai-astar;109;110;111;111;112;111;113;111;109;109 03:57:29 INFO - PROCESS | 3342 | |1;audio-beat-detection;143;118;115;150;116;122;120;120;148;115 03:57:29 INFO - PROCESS | 3342 | |2;audio-dft;160;160;163;162;160;161;161;160;161;159 03:57:29 INFO - PROCESS | 3342 | |3;audio-fft;75;75;75;114;75;75;75;75;104;75 03:57:29 INFO - PROCESS | 3342 | |4;audio-oscillator;82;81;106;81;82;83;99;81;82;113 03:57:29 INFO - PROCESS | 3342 | |5;imaging-gaussian-blur;116;116;116;117;117;117;117;116;117;117 03:57:29 INFO - PROCESS | 3342 | |6;imaging-darkroom;145;147;145;145;146;146;146;146;146;146 03:57:29 INFO - PROCESS | 3342 | |7;imaging-desaturate;94;95;97;94;95;94;95;95;95;95 03:57:29 INFO - PROCESS | 3342 | |8;json-parse-financial;59;49;50;50;50;51;51;50;50;49 03:57:29 INFO - PROCESS | 3342 | |9;json-stringify-tinderbox;59;60;60;59;60;59;59;59;59;60 03:57:29 INFO - PROCESS | 3342 | |10;stanford-crypto-aes;74;74;74;76;73;74;73;73;72;79 03:57:29 INFO - PROCESS | 3342 | |11;stanford-crypto-ccm;131;122;124;132;116;121;120;134;155;117 03:57:29 INFO - PROCESS | 3342 | |12;stanford-crypto-pbkdf2;156;150;151;152;151;149;150;152;151;151 03:57:29 INFO - PROCESS | 3342 | |13;stanford-crypto-sha256-iterative;60;62;62;61;61;66;61;63;61;61 03:57:29 INFO - PROCESS | 3342 | __end_tp_report 03:57:29 INFO - PROCESS | 3342 | __start_cc_report 03:57:29 INFO - PROCESS | 3342 | _x_x_mozilla_cycle_collect,1645 03:57:29 INFO - PROCESS | 3342 | __end_cc_report 03:57:29 INFO - PROCESS | 3342 | __startTimestamp1477738649543__endTimestamp 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | ------- Summary: start ------- 03:57:29 INFO - PROCESS | 3342 | Number of tests: 14 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:1.35 (1.2%) stddev-sans-first:1.30 03:57:29 INFO - PROCESS | 3342 | Values: 109.0 110.0 111.0 111.0 112.0 111.0 113.0 111.0 109.0 109.0 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | [#1] audio-beat-detection Cycles:10 Average:126.70 Median:121.00 stddev:14.29 (11.8%) stddev-sans-first:13.89 03:57:29 INFO - PROCESS | 3342 | Values: 143.0 118.0 115.0 150.0 116.0 122.0 120.0 120.0 148.0 115.0 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 03:57:29 INFO - PROCESS | 3342 | Values: 160.0 160.0 163.0 162.0 160.0 161.0 161.0 160.0 161.0 159.0 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | [#3] audio-fft Cycles:10 Average:81.80 Median:75.00 stddev:14.53 (19.4%) stddev-sans-first:15.20 03:57:29 INFO - PROCESS | 3342 | Values: 75.0 75.0 75.0 114.0 75.0 75.0 75.0 75.0 104.0 75.0 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:82.00 stddev:12.20 (14.9%) stddev-sans-first:12.68 03:57:29 INFO - PROCESS | 3342 | Values: 82.0 81.0 106.0 81.0 82.0 83.0 99.0 81.0 82.0 113.0 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 03:57:29 INFO - PROCESS | 3342 | Values: 116.0 116.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 03:57:29 INFO - PROCESS | 3342 | Values: 145.0 147.0 145.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.88 (0.9%) stddev-sans-first:0.87 03:57:29 INFO - PROCESS | 3342 | Values: 94.0 95.0 97.0 94.0 95.0 94.0 95.0 95.0 95.0 95.0 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:50.00 stddev:2.92 (5.8%) stddev-sans-first:0.71 03:57:29 INFO - PROCESS | 3342 | Values: 59.0 49.0 50.0 50.0 50.0 51.0 51.0 50.0 50.0 49.0 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 03:57:29 INFO - PROCESS | 3342 | Values: 59.0 60.0 60.0 59.0 60.0 59.0 59.0 59.0 59.0 60.0 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:74.00 stddev:1.99 (2.7%) stddev-sans-first:2.11 03:57:29 INFO - PROCESS | 3342 | Values: 74.0 74.0 74.0 76.0 73.0 74.0 73.0 73.0 72.0 79.0 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | [#11] stanford-crypto-ccm Cycles:10 Average:127.20 Median:127.50 stddev:11.61 (9.1%) stddev-sans-first:12.23 03:57:29 INFO - PROCESS | 3342 | Values: 131.0 122.0 124.0 132.0 116.0 121.0 120.0 134.0 155.0 117.0 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.30 Median:151.00 stddev:1.89 (1.3%) stddev-sans-first:0.97 03:57:29 INFO - PROCESS | 3342 | Values: 156.0 150.0 151.0 152.0 151.0 149.0 150.0 152.0 151.0 151.0 03:57:29 INFO - PROCESS | 3342 | 03:57:29 INFO - PROCESS | 3342 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:61.50 stddev:1.69 (2.7%) stddev-sans-first:1.66 03:57:29 INFO - PROCESS | 3342 | Values: 60.0 62.0 62.0 61.0 61.0 66.0 61.0 63.0 61.0 61.0 03:57:29 INFO - PROCESS | 3342 | -------- Summary: end -------- 03:57:29 INFO - PROCESS | 3342 | 03:57:30 INFO - TEST-INFO | 3342: exit 0 03:57:30 INFO - TEST-OK | kraken | took 59865ms 03:57:30 INFO - SUITE-END | took 1173s 03:57:30 INFO - Completed test suite (00:19:33) 03:57:30 INFO - javascript benchmark 03:57:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3144.7641426892983, 3204.8557814898327, 3215.9196020099507, 3239.8542065607044, 3255.2024753935275, 5552.805651802183, 5670.432956704328, 5694.54443644508, 5703.173039909221, 5705.229793977812, 5651.9152212716735, 5709.257796486461, 5713.457221563945, 5726.083826587746, 5750.539956803456, 5143.357080364945, 5147.356580427447, 5166.793328266865, 5201.193814958688, 5242.370915490127, 5130.486951304858, 5234.790608375661, 5245.291885595453, 5249.790008399659, 5280.313559237294, 5098.133317336044, 5099.133147364957, 5124.743762811863, 5130.02529519392, 5200.843974680765, 5231.372235331765, 5268.710220937847, 5273.604479664041, 5351.759170837322, 5362.302900622933, 5200.193969934666, 5241.86895327616, 5302.522772950414, 5313.973430132824, 5322.973385133049, 5565.777368905239, 5577.024020796344, 5592.5805564582815, 5613.045782217033, 5620.241267428885, 6011.368806275365, 6019.668918209497, 6119.571629985903, 6122.418370254819, 6126.264848218198, 6125.540584456184, 6161.630302181883, 6168.907466387963, 6174.413430724074, 6180.505559555225, 5571.303587051619, 5574.888502229973, 5597.356304025007, 5610.943890561083, 5610.943890561124, 6133.86054778377, 6161.630302181883, 6166.753329866845, 6167.259928808572, 6185.690715464209, 5947.6431414115295, 5949.8215053548465, 5950.077737950624, 5951.5238780897425, 5976.491998180203, 5895.086261629454, 5939.168516407706, 5971.731272092767, 5990.430909063632, 6016.669083200379, 6049.667268300285, 6050.183225264621, 6051.183090282755, 6076.240469941257, 6090.086487026892, 6009.939900601025, 6023.879522409527, 6059.848503787441, 6061.636301821904, 6061.878762424727, 4546.0680560485025, 4582.289745089516, 4638.512956139624, 4672.088942656217, 4672.2991551266905, 4137.710360274812, 4184.560621134798, 4185.665146788249, 4197.664186865043, 4236.406903033578, 5844.1818145459665, 5860.531157507389, 5861.296644402699, 5862.062070068769, 5867.207926929896, 6011.519078473799, 6033.698315084228, 6168.938310616926, 6169.96915015422, 6179.598326108789, 5770.653760774367, 5832.0, 5834.649921004753, 5883.764649414061, 5912.822615321547, 5347.545458635982, 5376.91934620981, 5378.408375078739, 5380.488853558905, 5387.81142660005, 6381.936180638227, 6411.390917862889, 6425.485961123006, 6427.164468619143, 6437.324080971524, 5940.910886336622, 5979.1031345296815, 6033.155358249936, 6076.483498902643, 6088.117223002551, 6327.209098862642, 6339.524535659752, 6348.968255158695, 6356.809295721136, 6357.268914074755, 6012.458878700938, 6030.095485677183, 6030.517558595389, 6033.034714445667, 6056.121862329849, 5893.6463812171405, 5934.258217722785, 5953.7023148843255, 5972.462478376967, 6073.240844894388, 5928.318243401892, 5931.733072011683, 5940.405959404025, 5947.316058653138, 5982.700864956648, 5900.527957763283, 5932.199153114447, 5933.050711886078, 5935.851603709942, 5942.762289508371, 5738.8278351649515, 5807.970960145341, 5816.302043754777, 5898.528117750484, 5912.231409916855, 5947.405259474018, 5990.580659353804, 6026.7287972040485, 6034.517238620812, 6044.455998960115, 5662.377138514677, 5869.06095024794, 5907.232059832366, 5919.940800591939, 5921.644701317935], "value": 5612.702775337402, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.3111225422383, 613.4693490131037, 641.4996302883752, 647.36881540498, 649.6134799794124, 35918.46122308167, 38762.22475550491, 46789.999999999956, 47453.0, 47662.0, 48827.51172488283, 49199.26201106986, 49311.260331095065, 49370.753146234216, 49445.25832112521, 9261.212796912254, 9420.340576159671, 9421.293402994768, 9431.622735090588, 9435.103665151799, 8528.530930798835, 8745.562721863913, 10881.0, 11213.383263920481, 11221.943890280538, 11286.98417142457, 11380.317180969168, 11384.74767775544, 11401.885981140165, 11410.20128590999, 8484.024337201177, 11166.776664466706, 11244.831327530093, 11245.437728113644, 11268.999999999958, 8390.538520381377, 10706.036456718934, 11076.0, 11080.722981925435, 11425.0, 45107.0, 45107.548924510666, 45465.31802022972, 45477.54522454799, 45494.54505454936, 3484.860605575774, 3598.442241452579, 3782.583915769263, 3944.23087497938, 4239.8092085856215, 34963.0, 35158.824205879064, 35239.647603523896, 35260.118497037525, 36687.0, 4687.343771871941, 4760.547747963972, 4779.187538118528, 4801.135795556798, 4807.567318941312, 263674.0, 263682.0, 265811.0, 266329.67846125487, 266399.0, 39417.40873886894, 40431.59568404337, 62469.37530624727, 62800.0, 62883.68558157272, 41558.58441415547, 41901.580984189764, 41987.37018944718, 41998.58001420008, 42003.78998105051, 15567.065976041475, 15612.531624051297, 15619.60950976212, 15625.765613515807, 15637.68724625501, 12370.876291236973, 12621.0, 12838.48646054166, 12855.292958887165, 12876.935615321863, 9321.766955826022, 9438.858417123749, 9609.471479068718, 9633.759156021155, 9747.268954828416, 3646.21606354635, 3684.8157592120288, 3685.7419980601635, 3708.8887333380044, 3721.14413684854, 1051.8780696347185, 1121.3888430805068, 1160.4354376827139, 1211.1340391620033, 1229.170310040719, 35096.47355289673, 35424.29151417009, 35779.28441431209, 35833.0, 35949.281014379565, 1153.4405813180601, 1154.509333533245, 1184.6742145909943, 1228.7419641875128, 1244.570623135035, 18194.0, 18302.633947321247, 18592.81407185938, 18642.81357186438, 18724.719129213074, 547448.0, 554174.0, 555487.0, 556296.0, 556548.0, 63308.0, 63618.36381636124, 63704.0, 63713.0, 63795.36204637894, 1333.2000799520404, 1382.1292585671165, 1415.1084816565499, 1431.921244331551, 1444.6388402899274, 33657.49513757246, 35710.8214458926, 36170.27659446744, 36218.0, 36280.4557931626, 53204.73397632987, 53764.0, 53825.73087134696, 53835.19247211217, 53839.0, 399127.0, 399157.0, 399970.0, 400397.0, 400618.0, 49750.0, 49816.50183498119, 50158.49841501538, 51067.744661276454, 51613.741931290104, 50091.0, 51439.742801285756, 54757.72621136869, 55079.44920550743, 55130.44869551253, 44611.77694111509, 46090.30864537102, 46170.0, 48005.0, 49069.01861962812, 45882.54117458916, 46822.0, 47442.52557474381, 47840.282395763395, 48136.27795582999, 46186.30720539128, 46305.768471157426, 46403.76798115988, 47055.52944470511, 47107.764461177474, 40215.1956960865, 42354.364684530374, 42402.0, 42532.574674252864, 43381.13237735164, 30343.696563034086, 30747.846260768554, 30755.0, 30789.846050769604, 31159.376812464077], "value": 20719.56604020978, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.1742050404402, 419.3269801967842, 419.5175548119662, 424.89377655586105, 439.26203977318113, 14733.189674567895, 16546.172691365427, 20338.288159914406, 20482.48793780156, 21893.343199704035, 19441.902790486063, 19717.309894153706, 19786.901065494687, 19938.900305498453, 20440.28459003935, 5786.3924287949685, 5823.475887170154, 5871.588988770788, 5916.556258280646, 5943.55423343251, 4381.627561657263, 5886.087656413263, 5976.2230909981645, 5977.701114944235, 5978.671173085479, 4686.0, 6712.362325579063, 6735.292794256606, 6743.629100399476, 6756.628385438799, 5892.086726557369, 6128.142060111588, 6138.416850399205, 6714.899276510856, 6745.797626071226, 6016.308124565688, 6030.638161710312, 6036.517078633743, 6036.96981515094, 6039.7282122304605, 15333.846661533353, 15403.22983850803, 15599.1420471874, 15633.687326253525, 17530.824691753045, 2430.4774473488283, 2481.888315025828, 2503.349129226396, 2609.908653197148, 3084.90745277642, 10640.148788096934, 11109.500072496758, 11205.327680339205, 11218.943905280503, 11328.449437755185, 2043.609613824474, 2096.109153609725, 2110.6939493773316, 2115.0376578656674, 2162.632352500063, 67335.32664673388, 70726.64636676836, 73810.26189738087, 82465.58767206185, 90909.0, 11947.761044779056, 11980.341081240615, 12148.635540933787, 12320.137590368588, 12329.383530823423, 17689.734653980202, 17704.203310851044, 17706.29174833018, 17716.379926702502, 17723.202455889517, 8519.57402129891, 8607.526586037828, 8649.1350864913, 8654.134586541295, 9031.45811251327, 8083.514989100555, 8114.513129212266, 8279.917200828035, 8305.916940830635, 8309.875351869727, 5174.97412512935, 5243.68537887728, 5277.340332458442, 5340.786368545292, 5370.650907690988, 2135.7437107547194, 2139.1443422631073, 2140.860844045163, 2143.5284237468045, 2189.788303836858, 453.6053633339016, 462.79405664478986, 1013.0730381700808, 1034.270839058462, 1085.0885256384674, 12824.422900969479, 13135.868641313657, 13541.255230962195, 13598.38807253676, 13788.103773254707, 479.1642898596711, 480.9615230781599, 484.14306677181895, 489.9461059283476, 496.201116202913, 7128.144622645212, 7247.746328878464, 7249.673764680602, 7250.383717383976, 7294.270572942663, 555089.0, 555515.0, 555572.0, 579100.0, 581794.0, 105435.47282263539, 107546.0, 107965.0, 108535.0, 110780.0, 478.6505850728987, 493.3709520361496, 493.96295277854307, 494.8911239527298, 495.37335270882363, 9787.902120978984, 11628.53485860556, 11964.58123965657, 12230.44962976686, 12362.876371236172, 45255.32117018313, 47893.5210647889, 48415.75792121017, 49453.75273123611, 49601.50398495969, 56384.71807640936, 61843.6907815458, 62049.0, 62921.68539157458, 64240.0, 8899.955500222717, 9320.207782338306, 9366.016568260235, 9491.38306010107, 9578.760530986781, 12562.811557826457, 14671.413143474141, 14709.485168019068, 14734.557963261119, 14808.555743327717, 12096.153269271419, 12126.939365303117, 12265.080118990933, 12265.57070502528, 12862.614121576367, 5642.0, 7269.273072692688, 9687.370320929118, 11831.704207394883, 12278.079144064051, 7385.889211661937, 12413.689657758267, 12425.62723118308, 12595.118341715992, 13109.0, 9460.479673618018, 9498.572564234764, 9541.618335266512, 9682.515874206121, 9736.75658108553, 10779.407132607781, 12436.81344779811, 12472.625821225378, 12584.244945303311, 12612.558560450707], "value": 8871.986003576296, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1536.3854458216713, 1550.899191552549, 1732.7314266288722, 1789.105447276362, 1837.660032893914, 8638.524881131536, 9232.584533695983, 9238.214751746093, 9244.630214791408, 9250.074992500746, 7755.689772409111, 8104.149064348247, 8598.269147122503, 8994.595243214055, 9000.45997240166, 2480.751924807518, 2533.1767175667887, 2536.822422430431, 2537.479816637585, 2549.146036077907, 2503.0, 2586.0819409109777, 2587.1979686297213, 2589.8187126901125, 2592.4296654735926, 2542.211914306571, 2550.477152183807, 2588.1071030494545, 2591.9740802592014, 2592.948141037178, 2507.7868381187623, 2510.8619025953567, 2513.8617376044217, 2541.567933451318, 2545.8217924745277, 2453.0800949643885, 2480.1071614218868, 2489.116363690882, 2492.0654754467073, 2503.9749602503925, 8748.69379571712, 8811.295096392274, 8823.205911467998, 8823.294136469067, 8844.955775221148, 5477.534409575191, 5611.186377975211, 5614.775408983635, 5615.0, 5625.156226566007, 3650.379435495972, 3734.2531493701367, 3794.4877441545314, 3814.122751767109, 3822.120912190212, 1231.2243286729392, 1235.0736947289533, 1251.5494422008069, 1286.6847622332589, 1300.5123078845434, 11448.484818183202, 11843.940780296043, 11872.52509899603, 12106.636800895987, 12192.634220973385, 4963.947172488894, 4973.850784476472, 5017.498250175026, 5047.974760126176, 5121.769520371592, 10748.677539673823, 10797.028267455968, 10804.297720648132, 10816.567337306577, 10816.67549973502, 5625.353084395265, 5637.830865074055, 5641.576881733804, 5644.774209031592, 5650.4349565043985, 4569.543045695404, 4611.769411529477, 5106.7191304477865, 5134.486551344836, 5182.818601348935, 4380.4305440292555, 4435.645148388121, 4539.092181563635, 4545.499995000548, 4563.155816174019, 1443.0, 1475.4467074846932, 1479.7336479433804, 1493.081754720839, 1502.353987785263, 782.3271986091956, 879.1735768377705, 932.398602901125, 994.1251698505414, 996.8853581838182, 6209.161763161916, 6304.968475157595, 6347.428731414103, 6357.205349331333, 6472.126262954535, 1005.8139244239838, 1049.1449468683152, 1054.3673795722505, 1064.9627263045757, 1091.3397394576325, 4480.775961201927, 4506.616937560344, 4610.677252592353, 4614.700044497097, 4710.411198600175, 10948.726281843017, 11301.378424186749, 11303.547858085749, 11410.0, 11429.25709828875, 10340.172786177087, 10741.087007604441, 10771.569137234423, 11421.37182454973, 11457.369844658457, 7922.128565857751, 8085.555294458862, 8114.8782768258525, 8127.959360203279, 8131.349492040505, 6108.175396321618, 6194.101855230876, 6211.751529938932, 6339.144215530844, 6441.066045423294, 10639.521221545205, 10665.933406659273, 10762.54705614732, 10785.730356741144, 10807.405592692478, 10258.38449692991, 10891.346519208873, 10891.509882055168, 10908.78182436331, 11020.779584408427, 7137.857242855218, 7469.327760501581, 7502.324790768639, 7726.7681969541, 7869.606519674108, 8047.798805029921, 8410.074891761778, 8428.662853485792, 8500.31997440191, 8504.617292221988, 7633.732819351296, 7785.377169826514, 7861.921380786119, 7925.08861480914, 7938.24586664266, 7662.693492260247, 7745.690172393041, 7776.455648104578, 7851.528908265523, 7877.409194310335, 6956.2000369958105, 6987.860242795217, 7022.508424410446, 7046.436285097282, 7087.610181439864, 5208.895822083613, 5271.7891284349735, 5342.1452567589, 5349.57203423733, 5466.371367292653, 5246.871922536673, 5431.701256430934, 5433.239346491573, 5435.755391007493, 5566.582506312124], "value": 4879.687913071726, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.34426663073793, 343.90198793343893, 344.3732407019226, 348.3051312631301, 348.35728081689274, 5567.248421463101, 5573.944260557393, 5612.943870561298, 5623.325200975878, 5641.887162256758, 5180.0, 5259.790887827112, 5320.780927538842, 5391.379991301002, 5502.339719233698, 4280.443542339492, 4286.849960251392, 4289.806958686867, 4290.270653988814, 4295.420118284039, 4292.635126014293, 4301.569843015689, 4303.569643035703, 4306.504751953518, 4328.913421731563, 4147.896302592444, 4216.662666986649, 4274.722143060691, 4282.186384586934, 4334.154839806239, 4268.850590229343, 4272.252355837716, 4280.229558679436, 4280.400743895857, 4286.164197981395, 3751.681107105872, 3766.7739935603677, 3767.9811600942094, 3795.35478968576, 3799.8670046548514, 5242.034237161065, 5369.301990741179, 5403.0, 5403.5136837684795, 5431.619786614939, 4850.860047888743, 4862.0, 4871.805127794884, 4895.192293271606, 4897.926531102036, 5696.8860622787315, 5715.599908006441, 5719.142128680731, 5722.313322401338, 5744.626599271034, 4031.29479216436, 4059.106996460774, 4073.6333729964445, 4127.133302006582, 4147.212029714375, 5945.721669840962, 5981.444824345658, 5988.461038506555, 5999.460048595603, 6008.309044459856, 4211.894702632398, 4213.957860421357, 4221.472315960505, 4238.385434112037, 4244.087521182961, 4132.421460995463, 4278.823323586039, 4289.978550107229, 4294.046208974909, 4299.312110062438, 4828.927566086511, 4840.661153719206, 4857.854264372075, 4862.270659401047, 4871.561559459595, 5739.7704091836695, 5743.913841292384, 5768.538516918637, 5769.249997500298, 5783.84780294732, 1391.1653008195, 1391.547746982235, 1431.7566013777682, 1442.4807069454987, 1454.7672372420361, 945.2154711589365, 967.7967626798311, 1003.9719766072493, 1005.8038682456997, 1010.368519675203, 5556.166575013699, 5559.777608895599, 5577.191307260425, 5577.832665019975, 5632.662040277596, 5725.656460612377, 5911.349751527328, 5914.817036592699, 5942.762289508371, 5956.434138756811, 5541.695206763667, 5569.359523654751, 5573.386927437979, 5581.441855814467, 5600.899662626515, 3095.4307924422674, 3103.8758449662214, 3123.9843800781305, 3124.7812653114065, 3125.1718294651937, 5884.705764711747, 5946.643201407929, 6006.849828754316, 6015.826913751864, 6039.48664363534, 5918.928375068692, 5972.492338151219, 5976.0, 5984.31180414258, 6002.369751176064, 5809.622374545641, 6014.127951447016, 6017.518598512109, 6035.706431556068, 6049.3950604939155, 5863.355030946507, 5883.823485295448, 5888.735006924613, 5909.793157239476, 5911.497522710618, 5819.738111784895, 5823.242978412779, 5836.620619659709, 5882.470577648032, 5920.69093053718, 5817.476427121579, 6106.877862442638, 6128.846778830387, 6148.354422785543, 6165.5375846810775, 4229.323308270662, 4243.660507159481, 4272.91454170921, 4286.65706743466, 4295.076558539929, 5787.826365209051, 6020.585162486725, 6030.879382412415, 6042.758289668364, 6047.637141771508, 5748.712564371848, 5781.682007489629, 5784.681842498703, 5794.5364370851075, 5796.188533605215, 5783.103618939072, 5789.739461724148, 5792.247007889116, 5807.0998995155815, 5859.589828711949, 5356.946430535645, 5488.670679759227, 5742.138679198154, 5776.660452516738, 5782.335031471434, 5810.883782324246, 5817.156512305607, 5847.736851841592, 5848.619839710405, 5855.238818953509, 5569.972150139223, 5646.797748877859, 5685.403032681509, 5687.744051517774, 5695.572832037697], "value": 4392.443087881477, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.301168714414, 666.1739443090567, 697.5152269172924, 762.8664983627865, 805.472415567803, 16165.676686466279, 16833.074180920044, 16891.83108168921, 16894.915525422355, 16899.74650380244, 15349.769753453707, 15387.99978001432, 15431.45989890354, 15446.459373921914, 15448.691026179498, 840.5503055865098, 889.9332550058739, 893.3166127912152, 893.8078313162669, 893.852514335134, 884.9203571678547, 887.2236792806294, 887.7248053103558, 888.7156110044788, 888.8800011998394, 937.9624815007392, 938.8450905600567, 939.7556635274809, 940.1444685336345, 940.9106134917206, 935.2471260635186, 935.2658163341778, 935.4106912645058, 935.5369092299287, 936.6159874451477, 684.7774473296199, 688.1123350877363, 688.3804575881696, 688.4526801193064, 688.5421194905382, 16733.330666773316, 16747.0, 16762.32950681971, 16788.496345109666, 16793.66412671752, 13892.23592702401, 14152.433902643985, 14251.429942802275, 14545.0, 14650.487232946902, 11713.180077394585, 11737.53049878004, 12136.08979326554, 12139.817902731465, 12879.227246365155, 4658.487566367697, 4676.579107880307, 4724.952750472486, 4764.166270902613, 4920.261960705922, 80046.19953800504, 82966.58516707378, 83286.58356708178, 84119.5794021026, 84265.0, 11329.716757080974, 11345.886541134649, 11351.751307356184, 11380.715482112848, 11473.598424055284, 20813.375598732062, 20846.270380536607, 20863.269785557735, 20864.165433382797, 20868.26961056386, 8758.124187581318, 8813.427127236837, 8873.245774109144, 8884.73345799627, 8885.822283554422, 4847.3698419205275, 4911.877203069952, 4927.359443272351, 5035.018171456531, 5041.319421878073, 3226.1934516370907, 3332.766706330582, 3337.0489410517885, 3337.4493208620793, 3349.062262566485, 570.566369559138, 578.3118089473597, 586.9501092407107, 592.1059200607115, 600.342624825815, 636.4208570201095, 638.9808305750835, 660.1550015979553, 664.7872680742116, 678.3996163395332, 9187.035361287102, 9226.35415520907, 9350.483533912216, 9399.483028433502, 9501.287403444769, 589.8764447987212, 621.0373920423326, 646.3052218864739, 647.3688154049764, 652.768267265114, 4651.209294419957, 4698.741569213657, 4716.245400735866, 4746.5490778376, 4787.4494433139935, 105335.0, 132498.0, 133749.33125334312, 134020.32989835183, 136029.0, 94761.52619236997, 111847.44076279567, 112855.4357228225, 113896.29155562673, 115498.0, 60778.0, 68182.0, 99752.0, 102203.0, 102819.48590257001, 8549.0, 8751.606177721891, 8821.955890220508, 8852.291816654524, 8858.311974459242, 58956.41043589509, 59898.401015991025, 60565.69717151386, 61501.0, 62085.0, 80515.59742201252, 87563.56218218867, 91277.0, 91531.0, 91616.0, 11000.889991099986, 11051.447427628425, 11162.38606876597, 11182.0, 11835.763284734428, 11861.347625880659, 12114.0308775296, 12199.329036903055, 12287.385630718607, 12316.81524777147, 10439.217058720475, 10767.246292759432, 10964.890351096707, 11053.28153670009, 11097.0, 10900.607735627724, 11130.721731956766, 11347.943260283975, 11405.087592992706, 11920.32645946005, 11340.489677964346, 11341.716457088638, 11563.479643415898, 13205.867941320465, 14150.080244784056, 10952.945235274092, 10977.83533246986, 11387.886121139014, 11716.297022178696, 12107.0, 11430.771384572095, 11676.585368291366, 11714.23857449263, 11792.23350482216, 11980.880191197977], "value": 7630.5955697209465, "unit": "score"}], "name": "dromaeo_css", "value": 7433.818475235427, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 110.0, 111.0, 111.0, 112.0, 111.0, 113.0, 111.0, 109.0, 109.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 118.0, 115.0, 150.0, 116.0, 122.0, 120.0, 120.0, 148.0, 115.0], "value": 126.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 163.0, 162.0, 160.0, 161.0, 161.0, 160.0, 161.0, 159.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 114.0, 75.0, 75.0, 75.0, 75.0, 104.0, 75.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 106.0, 81.0, 82.0, 83.0, 99.0, 81.0, 82.0, 113.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 97.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 49.0, 50.0, 50.0, 50.0, 51.0, 51.0, 50.0, 50.0, 49.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 76.0, 73.0, 74.0, 73.0, 73.0, 72.0, 79.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 122.0, 124.0, 132.0, 116.0, 121.0, 120.0, 134.0, 155.0, 117.0], "value": 127.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 150.0, 151.0, 152.0, 151.0, 149.0, 150.0, 152.0, 151.0, 151.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 62.0, 61.0, 61.0, 66.0, 61.0, 63.0, 61.0, 61.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1450.9, "alertThreshold": 2.0}]} 03:57:30 INFO - Return code: 0 03:57:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:57:30 INFO - # TBPL SUCCESS # 03:57:30 INFO - Running post-action listener: _resource_record_post_action 03:57:30 INFO - [mozharness: 2016-10-29 10:57:30.877454Z] Finished run-tests step (success) 03:57:30 INFO - Running post-run listener: _resource_record_post_run 03:57:30 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 1998848; Write bytes: 357769216; Read time: 80; Write time: 212008 03:57:30 INFO - TinderboxPrint: CPU usage
12.9% 03:57:30 INFO - TinderboxPrint: I/O read bytes / time
1,998,848 / 80 03:57:30 INFO - TinderboxPrint: I/O write bytes / time
357,769,216 / 212,008 03:57:30 INFO - TinderboxPrint: CPU idle
8,271.2 (87.1%) 03:57:30 INFO - TinderboxPrint: CPU user
1,183.8 (12.5%) 03:57:30 INFO - TinderboxPrint: Swap in / out
0 / 0 03:57:30 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 108027904; Read time: 0; Write time: 89524 03:57:30 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 249741312; Read time: 12; Write time: 122484 03:57:31 INFO - Running post-run listener: _upload_blobber_files 03:57:31 INFO - Blob upload gear active. 03:57:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:57:31 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 03:57:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:57:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:57:31 INFO - (blobuploader) - INFO - Open directory for files ... 03:57:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:57:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:57:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:57:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:57:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:57:35 INFO - (blobuploader) - INFO - Done attempting. 03:57:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:57:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:57:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:57:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:57:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:57:36 INFO - (blobuploader) - INFO - Done attempting. 03:57:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:57:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:57:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:57:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:57:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:57:36 INFO - (blobuploader) - INFO - Done attempting. 03:57:36 INFO - (blobuploader) - INFO - Iteration through files over. 03:57:36 INFO - Return code: 0 03:57:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:57:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:57:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a12390a63ee6eb6727d0e02688f1b0539404f464345471c9c58ed758033c1e4fdbdc15ba1d5bceaef6df35ec744bf3020b56e4d499cf1f0b818759a6542463a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfdef321808af151a13da4c0c5cfc365c2dc9ca75b72098fa7d92ada0f87c2702ffde8ee1926f2fb3e0e46dcdb06fb5ab872f26b91dfaba6be0324d98b08c03e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/62404dd6d3947e53a5c60b47eadfd1baf01070070bf3d5f1400799825a52753fcee11aa64b335d47b278adb7c4893ce27c337b9db9fd3d5566b80b02763521c2"} 03:57:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:57:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:57:36 INFO - Contents: 03:57:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a12390a63ee6eb6727d0e02688f1b0539404f464345471c9c58ed758033c1e4fdbdc15ba1d5bceaef6df35ec744bf3020b56e4d499cf1f0b818759a6542463a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfdef321808af151a13da4c0c5cfc365c2dc9ca75b72098fa7d92ada0f87c2702ffde8ee1926f2fb3e0e46dcdb06fb5ab872f26b91dfaba6be0324d98b08c03e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/62404dd6d3947e53a5c60b47eadfd1baf01070070bf3d5f1400799825a52753fcee11aa64b335d47b278adb7c4893ce27c337b9db9fd3d5566b80b02763521c2"} 03:57:36 INFO - Running post-run listener: copy_logs_to_upload_dir 03:57:36 INFO - Copying logs to upload dir... 03:57:36 INFO - mkdir: /builds/slave/test/build/upload/logs 03:57:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.307659 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-10-29 03:57:36.508680) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 03:57:36.512841) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a12390a63ee6eb6727d0e02688f1b0539404f464345471c9c58ed758033c1e4fdbdc15ba1d5bceaef6df35ec744bf3020b56e4d499cf1f0b818759a6542463a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfdef321808af151a13da4c0c5cfc365c2dc9ca75b72098fa7d92ada0f87c2702ffde8ee1926f2fb3e0e46dcdb06fb5ab872f26b91dfaba6be0324d98b08c03e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/62404dd6d3947e53a5c60b47eadfd1baf01070070bf3d5f1400799825a52753fcee11aa64b335d47b278adb7c4893ce27c337b9db9fd3d5566b80b02763521c2"} build_url:https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011355 build_url: 'https://queue.taskcluster.net/v1/task/GTxdUlMpSESbMt-GfuQHTg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a12390a63ee6eb6727d0e02688f1b0539404f464345471c9c58ed758033c1e4fdbdc15ba1d5bceaef6df35ec744bf3020b56e4d499cf1f0b818759a6542463a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfdef321808af151a13da4c0c5cfc365c2dc9ca75b72098fa7d92ada0f87c2702ffde8ee1926f2fb3e0e46dcdb06fb5ab872f26b91dfaba6be0324d98b08c03e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/62404dd6d3947e53a5c60b47eadfd1baf01070070bf3d5f1400799825a52753fcee11aa64b335d47b278adb7c4893ce27c337b9db9fd3d5566b80b02763521c2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 03:57:36.542797) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 03:57:36.543177) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1477736582.651693-1967846508 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004396 ========= master_lag: 1.63 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 03:57:38.173780) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-29 03:57:38.174147) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-29 03:57:38.174855) ========= ========= Total master_lag: 1.78 =========