builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-040 starttime: 1482340294.72 results: success (0) buildid: 20161221073214 builduid: 5be9f23e544e400085352f9d099e7ab8 revision: 5b0afeaeebdd3a60e1885912cda2e48a9233be52 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 09:11:34.717204) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 09:11:34.717698) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 09:11:34.717984) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 09:11:34.733045) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:11:34.733435) ========= 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-1482340294.809857-331589976 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-21 09:11:35-- 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% 220M=0s 2016-12-21 09:11:35 (220 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.083382 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:11:34.830606) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:11:34.830931) ========= 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-1482340294.809857-331589976 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015371 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:11:34.880414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 09:11:34.880816) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5b0afeaeebdd3a60e1885912cda2e48a9233be52 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5b0afeaeebdd3a60e1885912cda2e48a9233be52 --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-1482340294.809857-331589976 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-21 09:11:35,789 truncating revision to first 12 chars 2016-12-21 09:11:35,789 Setting DEBUG logging. 2016-12-21 09:11:35,789 attempt 1/10 2016-12-21 09:11:35,789 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5b0afeaeebdd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 09:11:36,881 unpacking tar archive at: mozilla-central-5b0afeaeebdd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.430131 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 09:11:36.324458) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 09:11:36.324789) ========= script_repo_revision: 5b0afeaeebdd3a60e1885912cda2e48a9233be52 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 09:11:36.325185) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 09:11:36.325478) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 09:11:36.334815) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-12-21 09:11:36.335255) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1482340294.809857-331589976 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:11:37 INFO - MultiFileLogger online at 20161221 09:11:37 in /builds/slave/test 09:11:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:11:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:11:37 INFO - {'append_to_log': False, 09:11:37 INFO - 'base_work_dir': '/builds/slave/test', 09:11:37 INFO - 'blob_upload_branch': 'Firefox', 09:11:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:11:37 INFO - 'branch': 'Firefox', 09:11:37 INFO - 'buildbot_json_path': 'buildprops.json', 09:11:37 INFO - 'config_files': ('talos/linux_config.py',), 09:11:37 INFO - 'default_actions': ('clobber', 09:11:37 INFO - 'read-buildbot-config', 09:11:37 INFO - 'download-and-extract', 09:11:37 INFO - 'populate-webroot', 09:11:37 INFO - 'create-virtualenv', 09:11:37 INFO - 'install', 09:11:37 INFO - 'run-tests'), 09:11:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:11:37 INFO - 'download_minidump_stackwalk': True, 09:11:37 INFO - 'download_symbols': 'ondemand', 09:11:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:11:37 INFO - 'tooltool.py': '/tools/tooltool.py', 09:11:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:11:37 INFO - '/tools/misc-python/virtualenv.py')}, 09:11:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:11:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:11:37 INFO - 'installer_path': 'installer.exe', 09:11:37 INFO - 'log_level': 'info', 09:11:37 INFO - 'log_name': 'talos', 09:11:37 INFO - 'log_to_console': True, 09:11:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:11:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:11:37 INFO - 'opt_config_files': (), 09:11:37 INFO - 'pip_index': False, 09:11:37 INFO - 'sps_profile': False, 09:11:37 INFO - 'sps_profile_interval': 0, 09:11:37 INFO - 'suite': 'dromaeojs', 09:11:37 INFO - 'system_bits': '32', 09:11:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:11:37 INFO - 'title': 'talos-linux64-ix-040', 09:11:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:11:37 INFO - 'use_talos_json': True, 09:11:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:11:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:11:37 INFO - 'work_dir': 'build'} 09:11:37 INFO - [mozharness: 2016-12-21 17:11:37.344008Z] Running clobber step. 09:11:37 INFO - Running pre-action listener: _resource_record_pre_action 09:11:37 INFO - Running main action method: clobber 09:11:37 INFO - rmtree: /builds/slave/test/build 09:11:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:11:38 INFO - Running post-action listener: _resource_record_post_action 09:11:38 INFO - [mozharness: 2016-12-21 17:11:38.392297Z] Finished clobber step (success) 09:11:38 INFO - [mozharness: 2016-12-21 17:11:38.392398Z] Running read-buildbot-config step. 09:11:38 INFO - Running pre-action listener: _resource_record_pre_action 09:11:38 INFO - Running main action method: read_buildbot_config 09:11:38 INFO - Using buildbot properties: 09:11:38 INFO - { 09:11:38 INFO - "project": "", 09:11:38 INFO - "product": "firefox", 09:11:38 INFO - "script_repo_revision": "production", 09:11:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:11:38 INFO - "repository": "", 09:11:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:11:38 INFO - "buildid": "20161221073214", 09:11:38 INFO - "pgo_build": "True", 09:11:38 INFO - "basedir": "/builds/slave/test", 09:11:38 INFO - "buildnumber": 137, 09:11:38 INFO - "slavename": "talos-linux64-ix-040", 09:11:38 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:11:38 INFO - "platform": "ubuntu64_hw", 09:11:38 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:11:38 INFO - "branch": "mozilla-central", 09:11:38 INFO - "repo_path": "mozilla-central", 09:11:38 INFO - "revision": "5b0afeaeebdd3a60e1885912cda2e48a9233be52", 09:11:38 INFO - "stage_platform": "linux64-pgo", 09:11:38 INFO - "builduid": "5be9f23e544e400085352f9d099e7ab8", 09:11:38 INFO - "slavebuilddir": "test" 09:11:38 INFO - } 09:11:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:11:38 INFO - Running post-action listener: _resource_record_post_action 09:11:38 INFO - [mozharness: 2016-12-21 17:11:38.394883Z] Finished read-buildbot-config step (success) 09:11:38 INFO - [mozharness: 2016-12-21 17:11:38.394973Z] Running download-and-extract step. 09:11:38 INFO - Running pre-action listener: _resource_record_pre_action 09:11:38 INFO - Running main action method: download_and_extract 09:11:38 INFO - mkdir: /builds/slave/test/build/tests 09:11:38 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')]} 09:11:38 INFO - trying https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:11:38 INFO - Downloading https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:11:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:11:38 INFO - Downloaded 1361 bytes. 09:11:38 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:11:38 INFO - Using the following test package requirements: 09:11:38 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:11:38 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:11:38 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:11:38 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:38 INFO - u'jsshell-linux-x86_64.zip'], 09:11:38 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:11:38 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:11:38 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:11:38 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:11:38 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:11:38 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:11:38 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:11:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:11:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:11:38 INFO - Fetch https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:11:40 INFO - Expected file size: 21831396 09:11:40 INFO - Obtained file size: 21831396 09:11:44 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:11:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:11:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:11:44 INFO - Fetch https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:11:45 INFO - Expected file size: 21831396 09:11:45 INFO - Obtained file size: 21831396 09:11:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:11:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:11:50 INFO - Fetch https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:11:51 INFO - Expected file size: 14409734 09:11:51 INFO - Obtained file size: 14409734 09:11:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:51 INFO - trying https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:11:51 INFO - Downloading https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:11:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:11:54 INFO - Downloaded 59801424 bytes. 09:11:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:11:54 INFO - mkdir: /builds/slave/test/properties 09:11:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:11:54 INFO - Writing to file /builds/slave/test/properties/build_url 09:11:54 INFO - Contents: 09:11:54 INFO - build_url:https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:11:54 INFO - Running post-action listener: _resource_record_post_action 09:11:54 INFO - Running post-action listener: set_extra_try_arguments 09:11:54 INFO - [mozharness: 2016-12-21 17:11:54.703234Z] Finished download-and-extract step (success) 09:11:54 INFO - [mozharness: 2016-12-21 17:11:54.703462Z] Running populate-webroot step. 09:11:54 INFO - Running pre-action listener: _resource_record_pre_action 09:11:54 INFO - Running main action method: populate_webroot 09:11:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:11:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:11:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:11:54 INFO - '--tppagecycles', 09:11:54 INFO - '7'], 09:11:54 INFO - 'tests': ['tsvgm']}}, 09:11:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:11:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:11:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:11:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:11:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:11:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:11:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:11:54 INFO - 'talos_options': ['--disable-e10s'], 09:11:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:11:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:11:54 INFO - 'talos_options': ['--disable-e10s'], 09:11:54 INFO - 'tests': ['damp', 'tps']}, 09:11:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:11:54 INFO - 'tests': ['damp', 'tps']}, 09:11:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:11:54 INFO - 'tests': ['dromaeo_dom']}, 09:11:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:11:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:11:54 INFO - 'tests': ['basic_compositor_video']}, 09:11:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:11:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:11:54 INFO - 'tests': ['a11yr', 09:11:54 INFO - 'ts_paint', 09:11:54 INFO - 'tpaint', 09:11:54 INFO - 'sessionrestore', 09:11:54 INFO - 'sessionrestore_no_auto_restore', 09:11:54 INFO - 'tabpaint']}, 09:11:54 INFO - 'other-e10s': {'tests': ['a11yr', 09:11:54 INFO - 'ts_paint', 09:11:54 INFO - 'tpaint', 09:11:54 INFO - 'sessionrestore', 09:11:54 INFO - 'sessionrestore_no_auto_restore', 09:11:54 INFO - 'tabpaint']}, 09:11:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:11:54 INFO - 'ts_paint', 09:11:54 INFO - 'tpaint', 09:11:54 INFO - 'sessionrestore', 09:11:54 INFO - 'sessionrestore_no_auto_restore', 09:11:54 INFO - 'tabpaint']}, 09:11:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:11:54 INFO - 'ts_paint', 09:11:54 INFO - 'tpaint', 09:11:54 INFO - 'sessionrestore', 09:11:54 INFO - 'sessionrestore_no_auto_restore', 09:11:54 INFO - 'tabpaint']}, 09:11:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:11:54 INFO - 'tests': ['a11yr', 09:11:54 INFO - 'ts_paint', 09:11:54 INFO - 'tpaint', 09:11:54 INFO - 'sessionrestore', 09:11:54 INFO - 'sessionrestore_no_auto_restore', 09:11:54 INFO - 'tabpaint']}, 09:11:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:11:54 INFO - 'tests': ['a11yr', 09:11:54 INFO - 'ts_paint', 09:11:54 INFO - 'tpaint', 09:11:54 INFO - 'sessionrestore', 09:11:54 INFO - 'sessionrestore_no_auto_restore', 09:11:54 INFO - 'tabpaint']}, 09:11:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:11:54 INFO - 'tests': ['tsvgx', 09:11:54 INFO - 'tsvgr_opacity', 09:11:54 INFO - 'tart', 09:11:54 INFO - 'tscrollx', 09:11:54 INFO - 'cart', 09:11:54 INFO - 'tsvg_static']}, 09:11:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:11:54 INFO - 'tsvgr_opacity', 09:11:54 INFO - 'tart', 09:11:54 INFO - 'tscrollx', 09:11:54 INFO - 'cart', 09:11:54 INFO - 'tsvg_static']}, 09:11:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:11:54 INFO - 'talos_options': ['--disable-e10s'], 09:11:54 INFO - 'tests': ['tp5o']}, 09:11:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:11:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:11:54 INFO - 'talos_options': ['--disable-e10s', 09:11:54 INFO - '--xperf_path', 09:11:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:54 INFO - 'tests': ['tp5n']}, 09:11:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:11:54 INFO - 'talos_options': ['--xperf_path', 09:11:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:54 INFO - 'tests': ['tp5n']}}, 09:11:54 INFO - 'talos.zip': {'path': '', 09:11:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:11:54 INFO - Running post-action listener: _resource_record_post_action 09:11:54 INFO - [mozharness: 2016-12-21 17:11:54.724754Z] Finished populate-webroot step (success) 09:11:54 INFO - [mozharness: 2016-12-21 17:11:54.724844Z] Running create-virtualenv step. 09:11:54 INFO - Running pre-action listener: _resource_record_pre_action 09:11:54 INFO - Running main action method: create_virtualenv 09:11:54 INFO - Creating virtualenv /builds/slave/test/build/venv 09:11:54 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 09:11:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:11:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:11:54 INFO - Using real prefix '/usr' 09:11:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:11:55 INFO - Installing distribute.............................................................................................................................................................................................done. 09:11:57 INFO - Installing pip.................done. 09:11:57 INFO - Return code: 0 09:11:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:11:57 INFO - Reading from file tmpfile_stdout 09:11:57 INFO - Output received: 09:11:57 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:11:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16213f0>, '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 0x1621cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16221a0>, '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-1482340294.809857-331589976', '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 09:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:11:57 INFO - Using env: {'DISPLAY': ':0', 09:11:57 INFO - 'HOME': '/home/cltbld', 09:11:57 INFO - 'LANG': 'en_US.UTF-8', 09:11:57 INFO - 'LANGUAGE': 'en_US:en', 09:11:57 INFO - 'LOGNAME': 'cltbld', 09:11:57 INFO - 'MAIL': '/var/mail/cltbld', 09:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:57 INFO - 'MOZ_NO_REMOTE': '1', 09:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:57 INFO - 'NO_EM_RESTART': '1', 09:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:57 INFO - 'PWD': '/builds/slave/test', 09:11:57 INFO - 'SHELL': '/bin/bash', 09:11:57 INFO - 'SHLVL': '1', 09:11:57 INFO - 'TERM': 'linux', 09:11:57 INFO - 'TMOUT': '86400', 09:11:57 INFO - 'USER': 'cltbld', 09:11:57 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1482340294.809857-331589976', 09:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:57 INFO - '_': '/tools/buildbot/bin/python'} 09:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:57 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 09:11:57 INFO - Cleaning up... 09:11:57 INFO - Return code: 0 09:11:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16213f0>, '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 0x1621cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16221a0>, '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-1482340294.809857-331589976', '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 09:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:11:57 INFO - Using env: {'DISPLAY': ':0', 09:11:57 INFO - 'HOME': '/home/cltbld', 09:11:57 INFO - 'LANG': 'en_US.UTF-8', 09:11:57 INFO - 'LANGUAGE': 'en_US:en', 09:11:57 INFO - 'LOGNAME': 'cltbld', 09:11:57 INFO - 'MAIL': '/var/mail/cltbld', 09:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:57 INFO - 'MOZ_NO_REMOTE': '1', 09:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:57 INFO - 'NO_EM_RESTART': '1', 09:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:57 INFO - 'PWD': '/builds/slave/test', 09:11:57 INFO - 'SHELL': '/bin/bash', 09:11:57 INFO - 'SHLVL': '1', 09:11:57 INFO - 'TERM': 'linux', 09:11:57 INFO - 'TMOUT': '86400', 09:11:57 INFO - 'USER': 'cltbld', 09:11:57 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1482340294.809857-331589976', 09:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:57 INFO - '_': '/tools/buildbot/bin/python'} 09:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:57 INFO - Downloading/unpacking psutil>=3.1.1 09:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:12:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:00 INFO - Installing collected packages: psutil 09:12:00 INFO - Running setup.py install for psutil 09:12:00 INFO - building 'psutil._psutil_linux' extension 09:12:00 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 09:12:00 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 09:12:00 INFO - building 'psutil._psutil_posix' extension 09:12:00 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 09:12:00 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 09:12:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:00 INFO - Successfully installed psutil 09:12:00 INFO - Cleaning up... 09:12:00 INFO - Return code: 0 09:12:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:00 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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16213f0>, '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 0x1621cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16221a0>, '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-1482340294.809857-331589976', '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 09:12:00 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 09:12:00 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 09:12:00 INFO - Using env: {'DISPLAY': ':0', 09:12:00 INFO - 'HOME': '/home/cltbld', 09:12:00 INFO - 'LANG': 'en_US.UTF-8', 09:12:00 INFO - 'LANGUAGE': 'en_US:en', 09:12:00 INFO - 'LOGNAME': 'cltbld', 09:12:00 INFO - 'MAIL': '/var/mail/cltbld', 09:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:00 INFO - 'MOZ_NO_REMOTE': '1', 09:12:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:00 INFO - 'NO_EM_RESTART': '1', 09:12:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:00 INFO - 'PWD': '/builds/slave/test', 09:12:00 INFO - 'SHELL': '/bin/bash', 09:12:00 INFO - 'SHLVL': '1', 09:12:00 INFO - 'TERM': 'linux', 09:12:00 INFO - 'TMOUT': '86400', 09:12:00 INFO - 'USER': 'cltbld', 09:12:00 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1482340294.809857-331589976', 09:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:00 INFO - '_': '/tools/buildbot/bin/python'} 09:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:12:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:12:02 INFO - Installing collected packages: mozsystemmonitor 09:12:02 INFO - Running setup.py install for mozsystemmonitor 09:12:02 INFO - Successfully installed mozsystemmonitor 09:12:02 INFO - Cleaning up... 09:12:03 INFO - Return code: 0 09:12:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:03 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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16213f0>, '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 0x1621cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16221a0>, '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-1482340294.809857-331589976', '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 09:12:03 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 09:12:03 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 09:12:03 INFO - Using env: {'DISPLAY': ':0', 09:12:03 INFO - 'HOME': '/home/cltbld', 09:12:03 INFO - 'LANG': 'en_US.UTF-8', 09:12:03 INFO - 'LANGUAGE': 'en_US:en', 09:12:03 INFO - 'LOGNAME': 'cltbld', 09:12:03 INFO - 'MAIL': '/var/mail/cltbld', 09:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:03 INFO - 'MOZ_NO_REMOTE': '1', 09:12:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:03 INFO - 'NO_EM_RESTART': '1', 09:12:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:03 INFO - 'PWD': '/builds/slave/test', 09:12:03 INFO - 'SHELL': '/bin/bash', 09:12:03 INFO - 'SHLVL': '1', 09:12:03 INFO - 'TERM': 'linux', 09:12:03 INFO - 'TMOUT': '86400', 09:12:03 INFO - 'USER': 'cltbld', 09:12:03 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1482340294.809857-331589976', 09:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:03 INFO - '_': '/tools/buildbot/bin/python'} 09:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:03 INFO - Downloading/unpacking jsonschema==2.5.1 09:12:03 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 09:12:03 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 09:12:03 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 09:12:03 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 09:12:03 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 09:12:03 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 09:12:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:12:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:05 INFO - Downloading functools32-3.2.3-2.tar.gz 09:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:12:05 INFO - warning: no files found matching '*.txt' 09:12:05 INFO - no previously-included directories found matching 'build' 09:12:05 INFO - no previously-included directories found matching 'dist' 09:12:05 INFO - no previously-included directories found matching '.git*' 09:12:05 INFO - Installing collected packages: jsonschema, functools32 09:12:05 INFO - Running setup.py install for functools32 09:12:05 INFO - warning: no files found matching '*.txt' 09:12:05 INFO - no previously-included directories found matching 'build' 09:12:05 INFO - no previously-included directories found matching 'dist' 09:12:05 INFO - no previously-included directories found matching '.git*' 09:12:05 INFO - Successfully installed jsonschema functools32 09:12:05 INFO - Cleaning up... 09:12:05 INFO - Return code: 0 09:12:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:12:05 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')]} 09:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:05 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')]} 09:12:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:05 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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16213f0>, '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 0x1621cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16221a0>, '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-1482340294.809857-331589976', '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 09:12:05 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 09:12:05 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 09:12:05 INFO - Using env: {'DISPLAY': ':0', 09:12:05 INFO - 'HOME': '/home/cltbld', 09:12:05 INFO - 'LANG': 'en_US.UTF-8', 09:12:05 INFO - 'LANGUAGE': 'en_US:en', 09:12:05 INFO - 'LOGNAME': 'cltbld', 09:12:05 INFO - 'MAIL': '/var/mail/cltbld', 09:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:05 INFO - 'MOZ_NO_REMOTE': '1', 09:12:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:05 INFO - 'NO_EM_RESTART': '1', 09:12:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:05 INFO - 'PWD': '/builds/slave/test', 09:12:05 INFO - 'SHELL': '/bin/bash', 09:12:05 INFO - 'SHLVL': '1', 09:12:05 INFO - 'TERM': 'linux', 09:12:05 INFO - 'TMOUT': '86400', 09:12:05 INFO - 'USER': 'cltbld', 09:12:05 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1482340294.809857-331589976', 09:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:05 INFO - '_': '/tools/buildbot/bin/python'} 09:12:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:12:05 INFO - Cleaning up... 09:12:05 INFO - Return code: 0 09:12:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:12:05 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')]} 09:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:05 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')]} 09:12:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:05 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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16213f0>, '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 0x1621cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16221a0>, '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-1482340294.809857-331589976', '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 09:12:05 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 09:12:05 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 09:12:05 INFO - Using env: {'DISPLAY': ':0', 09:12:05 INFO - 'HOME': '/home/cltbld', 09:12:05 INFO - 'LANG': 'en_US.UTF-8', 09:12:05 INFO - 'LANGUAGE': 'en_US:en', 09:12:05 INFO - 'LOGNAME': 'cltbld', 09:12:05 INFO - 'MAIL': '/var/mail/cltbld', 09:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:05 INFO - 'MOZ_NO_REMOTE': '1', 09:12:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:05 INFO - 'NO_EM_RESTART': '1', 09:12:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:06 INFO - 'PWD': '/builds/slave/test', 09:12:06 INFO - 'SHELL': '/bin/bash', 09:12:06 INFO - 'SHLVL': '1', 09:12:06 INFO - 'TERM': 'linux', 09:12:06 INFO - 'TMOUT': '86400', 09:12:06 INFO - 'USER': 'cltbld', 09:12:06 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1482340294.809857-331589976', 09:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:06 INFO - '_': '/tools/buildbot/bin/python'} 09:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:06 INFO - Downloading/unpacking blobuploader==1.2.4 09:12:06 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 09:12:06 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 09:12:06 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 09:12:06 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 09:12:06 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 09:12:06 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 09:12:08 INFO - Downloading blobuploader-1.2.4.tar.gz 09:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:12:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:12:08 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 09:12:08 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 09:12:08 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 09:12:08 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 09:12:08 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 09:12:08 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 09:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:12:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:12:08 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 09:12:08 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 09:12:08 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 09:12:08 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 09:12:08 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 09:12:08 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 09:12:08 INFO - Downloading docopt-0.6.1.tar.gz 09:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:12:09 INFO - Installing collected packages: blobuploader, requests, docopt 09:12:09 INFO - Running setup.py install for blobuploader 09:12:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:12:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:12:09 INFO - Running setup.py install for requests 09:12:09 INFO - Running setup.py install for docopt 09:12:09 INFO - Successfully installed blobuploader requests docopt 09:12:09 INFO - Cleaning up... 09:12:09 INFO - Return code: 0 09:12:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:09 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')]} 09:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:09 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')]} 09:12:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:09 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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16213f0>, '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 0x1621cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16221a0>, '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-1482340294.809857-331589976', '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 09:12:09 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 09:12:09 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 09:12:09 INFO - Using env: {'DISPLAY': ':0', 09:12:09 INFO - 'HOME': '/home/cltbld', 09:12:09 INFO - 'LANG': 'en_US.UTF-8', 09:12:09 INFO - 'LANGUAGE': 'en_US:en', 09:12:09 INFO - 'LOGNAME': 'cltbld', 09:12:09 INFO - 'MAIL': '/var/mail/cltbld', 09:12:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:09 INFO - 'MOZ_NO_REMOTE': '1', 09:12:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:09 INFO - 'NO_EM_RESTART': '1', 09:12:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:09 INFO - 'PWD': '/builds/slave/test', 09:12:09 INFO - 'SHELL': '/bin/bash', 09:12:09 INFO - 'SHLVL': '1', 09:12:09 INFO - 'TERM': 'linux', 09:12:09 INFO - 'TMOUT': '86400', 09:12:09 INFO - 'USER': 'cltbld', 09:12:09 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1482340294.809857-331589976', 09:12:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:09 INFO - '_': '/tools/buildbot/bin/python'} 09:12:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:12:09 INFO - Running setup.py (path:/tmp/pip-HriVoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:12:09 INFO - Running setup.py (path:/tmp/pip-kfWibo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:12:10 INFO - Running setup.py (path:/tmp/pip-seYKck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:12:10 INFO - Running setup.py (path:/tmp/pip-0Xn8Hg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:12:10 INFO - Running setup.py (path:/tmp/pip-Fdd69T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:12:10 INFO - Running setup.py (path:/tmp/pip-oIO4fE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:12:10 INFO - Running setup.py (path:/tmp/pip-MxddIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:12:10 INFO - Running setup.py (path:/tmp/pip-Tp6DUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:12:10 INFO - Running setup.py (path:/tmp/pip-5gOtOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:12:10 INFO - Running setup.py (path:/tmp/pip-pVBopI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:12:10 INFO - Running setup.py (path:/tmp/pip-8urgP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:12:10 INFO - Running setup.py (path:/tmp/pip-KYCrCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:12:10 INFO - Running setup.py (path:/tmp/pip-BegU03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:12:10 INFO - Running setup.py (path:/tmp/pip-4GT_7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:10 INFO - Running setup.py (path:/tmp/pip-SvXKq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:12:10 INFO - Running setup.py (path:/tmp/pip-2AY8fv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:12:11 INFO - Running setup.py (path:/tmp/pip-AwoJ7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:12:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:12:11 INFO - Running setup.py install for manifestparser 09:12:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:12:11 INFO - Running setup.py install for mozcrash 09:12:11 INFO - Running setup.py install for mozdebug 09:12:11 INFO - Running setup.py install for mozdevice 09:12:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:12:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:12:11 INFO - Running setup.py install for mozfile 09:12:11 INFO - Running setup.py install for mozhttpd 09:12:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:12:11 INFO - Running setup.py install for mozinfo 09:12:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:12:11 INFO - Running setup.py install for mozInstall 09:12:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:12:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:12:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:12:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:12:11 INFO - Running setup.py install for mozleak 09:12:11 INFO - Running setup.py install for mozlog 09:12:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:12:12 INFO - Running setup.py install for moznetwork 09:12:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:12:12 INFO - Running setup.py install for mozprocess 09:12:12 INFO - Running setup.py install for mozprofile 09:12:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:12:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:12:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:12:12 INFO - Running setup.py install for mozrunner 09:12:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:12:12 INFO - Running setup.py install for mozscreenshot 09:12:12 INFO - Running setup.py install for moztest 09:12:12 INFO - Running setup.py install for mozversion 09:12:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:12:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:12:12 INFO - Cleaning up... 09:12:12 INFO - Return code: 0 09:12:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:12 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')]} 09:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:12 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')]} 09:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:12 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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16213f0>, '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 0x1621cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16221a0>, '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-1482340294.809857-331589976', '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 09:12:12 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 09:12:12 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 09:12:12 INFO - Using env: {'DISPLAY': ':0', 09:12:12 INFO - 'HOME': '/home/cltbld', 09:12:12 INFO - 'LANG': 'en_US.UTF-8', 09:12:12 INFO - 'LANGUAGE': 'en_US:en', 09:12:12 INFO - 'LOGNAME': 'cltbld', 09:12:12 INFO - 'MAIL': '/var/mail/cltbld', 09:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:12 INFO - 'MOZ_NO_REMOTE': '1', 09:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:12 INFO - 'NO_EM_RESTART': '1', 09:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:12 INFO - 'PWD': '/builds/slave/test', 09:12:12 INFO - 'SHELL': '/bin/bash', 09:12:12 INFO - 'SHLVL': '1', 09:12:12 INFO - 'TERM': 'linux', 09:12:12 INFO - 'TMOUT': '86400', 09:12:12 INFO - 'USER': 'cltbld', 09:12:12 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1482340294.809857-331589976', 09:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:12 INFO - '_': '/tools/buildbot/bin/python'} 09:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:12:13 INFO - Running setup.py (path:/tmp/pip-hCbqrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:12:13 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)) 09:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:12:13 INFO - Running setup.py (path:/tmp/pip-LC2kl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:12:13 INFO - Running setup.py (path:/tmp/pip-sElteI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:12:13 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)) 09:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:12:13 INFO - Running setup.py (path:/tmp/pip-bmhmvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:12:13 INFO - Running setup.py (path:/tmp/pip-7NMuwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:12:13 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)) 09:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:12:13 INFO - Running setup.py (path:/tmp/pip-agBPih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:12:13 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)) 09:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:12:13 INFO - Running setup.py (path:/tmp/pip-xkRHoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:12:13 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)) 09:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:12:13 INFO - Running setup.py (path:/tmp/pip-pcoq7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:12:13 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)) 09:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:12:13 INFO - Running setup.py (path:/tmp/pip-ayHWbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:12:13 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)) 09:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:12:13 INFO - Running setup.py (path:/tmp/pip-bQfVv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:12:13 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)) 09:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:12:13 INFO - Running setup.py (path:/tmp/pip-wugBRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:12:13 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)) 09:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:12:13 INFO - Running setup.py (path:/tmp/pip-nO1WjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:12:13 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)) 09:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:12:14 INFO - Running setup.py (path:/tmp/pip-JWbuMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:12:14 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)) 09:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:12:14 INFO - Running setup.py (path:/tmp/pip-vP6ylU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:12:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:14 INFO - Running setup.py (path:/tmp/pip-FnD5dl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:14 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)) 09:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:12:14 INFO - Running setup.py (path:/tmp/pip-nBLc6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:12:14 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)) 09:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:12:14 INFO - Running setup.py (path:/tmp/pip-us6kMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:12:14 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)) 09:12:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:12:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:12:14 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)) 09:12:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:12:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:12:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:12:14 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 09:12:14 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 09:12:14 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 09:12:14 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 09:12:14 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 09:12:14 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 09:12:16 INFO - Downloading blessings-1.6.tar.gz 09:12:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:12:16 INFO - Installing collected packages: blessings 09:12:16 INFO - Running setup.py install for blessings 09:12:16 INFO - Successfully installed blessings 09:12:16 INFO - Cleaning up... 09:12:16 INFO - Return code: 0 09:12:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:12:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:12:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:12:16 INFO - Reading from file tmpfile_stdout 09:12:16 INFO - Current package versions: 09:12:16 INFO - argparse == 1.2.1 09:12:16 INFO - blessings == 1.6 09:12:16 INFO - blobuploader == 1.2.4 09:12:16 INFO - docopt == 0.6.1 09:12:16 INFO - functools32 == 3.2.3-2 09:12:16 INFO - jsonschema == 2.5.1 09:12:16 INFO - manifestparser == 1.1 09:12:16 INFO - mozInstall == 1.12 09:12:16 INFO - mozcrash == 1.0 09:12:16 INFO - mozdebug == 0.1 09:12:16 INFO - mozdevice == 0.49 09:12:16 INFO - mozfile == 1.2 09:12:16 INFO - mozhttpd == 0.7 09:12:16 INFO - mozinfo == 0.9 09:12:16 INFO - mozleak == 0.1 09:12:16 INFO - mozlog == 3.4 09:12:16 INFO - moznetwork == 0.27 09:12:16 INFO - mozprocess == 0.23 09:12:16 INFO - mozprofile == 0.28 09:12:16 INFO - mozrunner == 6.13 09:12:16 INFO - mozscreenshot == 0.1 09:12:16 INFO - mozsystemmonitor == 0.3 09:12:16 INFO - moztest == 0.7 09:12:16 INFO - mozversion == 1.4 09:12:16 INFO - psutil == 3.1.1 09:12:16 INFO - requests == 1.2.3 09:12:16 INFO - wsgiref == 0.1.2 09:12:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:16 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')]} 09:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:16 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')]} 09:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:16 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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16213f0>, '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 0x1621cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16221a0>, '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-1482340294.809857-331589976', '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 09:12:16 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 09:12:16 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 09:12:16 INFO - Using env: {'DISPLAY': ':0', 09:12:16 INFO - 'HOME': '/home/cltbld', 09:12:16 INFO - 'LANG': 'en_US.UTF-8', 09:12:16 INFO - 'LANGUAGE': 'en_US:en', 09:12:16 INFO - 'LOGNAME': 'cltbld', 09:12:16 INFO - 'MAIL': '/var/mail/cltbld', 09:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:16 INFO - 'MOZ_NO_REMOTE': '1', 09:12:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:16 INFO - 'NO_EM_RESTART': '1', 09:12:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:16 INFO - 'PWD': '/builds/slave/test', 09:12:16 INFO - 'SHELL': '/bin/bash', 09:12:16 INFO - 'SHLVL': '1', 09:12:16 INFO - 'TERM': 'linux', 09:12:16 INFO - 'TMOUT': '86400', 09:12:16 INFO - 'USER': 'cltbld', 09:12:16 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1482340294.809857-331589976', 09:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:16 INFO - '_': '/tools/buildbot/bin/python'} 09:12:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:17 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)) 09:12:17 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)) 09:12:17 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)) 09:12:17 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)) 09:12:17 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)) 09:12:17 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)) 09:12:17 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)) 09:12:17 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)) 09:12:17 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)) 09:12:17 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)) 09:12:17 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)) 09:12:17 INFO - Cleaning up... 09:12:17 INFO - Return code: 0 09:12:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:12:17 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')]} 09:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:17 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')]} 09:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:17 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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16213f0>, '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 0x1621cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16221a0>, '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-1482340294.809857-331589976', '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 09:12:17 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 09:12:17 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 09:12:17 INFO - Using env: {'DISPLAY': ':0', 09:12:17 INFO - 'HOME': '/home/cltbld', 09:12:17 INFO - 'LANG': 'en_US.UTF-8', 09:12:17 INFO - 'LANGUAGE': 'en_US:en', 09:12:17 INFO - 'LOGNAME': 'cltbld', 09:12:17 INFO - 'MAIL': '/var/mail/cltbld', 09:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:17 INFO - 'MOZ_NO_REMOTE': '1', 09:12:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:17 INFO - 'NO_EM_RESTART': '1', 09:12:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:17 INFO - 'PWD': '/builds/slave/test', 09:12:17 INFO - 'SHELL': '/bin/bash', 09:12:17 INFO - 'SHLVL': '1', 09:12:17 INFO - 'TERM': 'linux', 09:12:17 INFO - 'TMOUT': '86400', 09:12:17 INFO - 'USER': 'cltbld', 09:12:17 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1482340294.809857-331589976', 09:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:17 INFO - '_': '/tools/buildbot/bin/python'} 09:12:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:12:17 INFO - Cleaning up... 09:12:17 INFO - Return code: 0 09:12:17 INFO - Running post-action listener: _resource_record_post_action 09:12:17 INFO - Running post-action listener: _start_resource_monitoring 09:12:17 INFO - Starting resource monitoring. 09:12:17 INFO - [mozharness: 2016-12-21 17:12:17.306084Z] Finished create-virtualenv step (success) 09:12:17 INFO - [mozharness: 2016-12-21 17:12:17.306565Z] Running install step. 09:12:17 INFO - Running pre-action listener: _resource_record_pre_action 09:12:17 INFO - Running main action method: install 09:12:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:12:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:12:17 INFO - Reading from file tmpfile_stdout 09:12:17 INFO - Detecting whether we're running mozinstall >=1.0... 09:12:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:12:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:12:17 INFO - Reading from file tmpfile_stdout 09:12:17 INFO - Output received: 09:12:17 INFO - Usage: mozinstall [options] installer 09:12:17 INFO - Options: 09:12:17 INFO - -h, --help show this help message and exit 09:12:17 INFO - -d DEST, --destination=DEST 09:12:17 INFO - Directory to install application into. [default: 09:12:17 INFO - "/builds/slave/test"] 09:12:17 INFO - --app=APP Application being installed. [default: firefox] 09:12:17 INFO - mkdir: /builds/slave/test/build/application 09:12:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:12:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:12:34 INFO - Reading from file tmpfile_stdout 09:12:34 INFO - Output received: 09:12:34 INFO - /builds/slave/test/build/application/firefox/firefox 09:12:34 INFO - Running post-action listener: _resource_record_post_action 09:12:34 INFO - [mozharness: 2016-12-21 17:12:34.596839Z] Finished install step (success) 09:12:34 INFO - [mozharness: 2016-12-21 17:12:34.597086Z] Running run-tests step. 09:12:34 INFO - Running pre-action listener: _resource_record_pre_action 09:12:34 INFO - Running main action method: run_tests 09:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:12:34 INFO - Python 2.7.3 09:12:34 INFO - Return code: 0 09:12:34 INFO - grabbing minidump binary from tooltool 09:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:34 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 0x16213f0>, '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 0x1621cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16221a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:12:34 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 09:12:34 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 09:12:34 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 09:12:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:12:34 INFO - Return code: 0 09:12:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:12:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:12:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:12:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:12:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.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 09:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.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 09:12:34 INFO - Using env: {'DISPLAY': ':0', 09:12:34 INFO - 'HOME': '/home/cltbld', 09:12:34 INFO - 'LANG': 'en_US.UTF-8', 09:12:34 INFO - 'LANGUAGE': 'en_US:en', 09:12:34 INFO - 'LOGNAME': 'cltbld', 09:12:34 INFO - 'MAIL': '/var/mail/cltbld', 09:12:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:12:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:34 INFO - 'MOZ_NO_REMOTE': '1', 09:12:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:34 INFO - 'NO_EM_RESTART': '1', 09:12:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:34 INFO - 'PWD': '/builds/slave/test', 09:12:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:12:34 INFO - 'SHELL': '/bin/bash', 09:12:34 INFO - 'SHLVL': '1', 09:12:34 INFO - 'TERM': 'linux', 09:12:34 INFO - 'TMOUT': '86400', 09:12:34 INFO - 'USER': 'cltbld', 09:12:34 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1482340294.809857-331589976', 09:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:34 INFO - '_': '/tools/buildbot/bin/python'} 09:12:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.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 09:12:34 INFO - mozversion application_buildid: 20161221073214 09:12:34 INFO - mozversion application_changeset: 5b0afeaeebdd3a60e1885912cda2e48a9233be52 09:12:34 INFO - mozversion application_display_name: Nightly 09:12:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:12:34 INFO - mozversion application_name: Firefox 09:12:34 INFO - mozversion application_remotingname: firefox 09:12:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:12:34 INFO - mozversion application_vendor: Mozilla 09:12:34 INFO - mozversion application_version: 53.0a1 09:12:34 INFO - mozversion platform_buildid: 20161221073214 09:12:34 INFO - mozversion platform_changeset: 5b0afeaeebdd3a60e1885912cda2e48a9233be52 09:12:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:12:34 INFO - mozversion platform_version: 53.0a1 09:12:34 INFO - using testdate: 1482340354 09:12:34 INFO - actual date: 1482340354 09:12:34 INFO - starting webserver on 'localhost:50135' 09:12:34 INFO - SUITE-START | Running 2 tests 09:12:34 INFO - TEST-START | dromaeo_css 09:12:34 INFO - operating with platform_type : linux_ 09:12:34 INFO - Initialising browser for dromaeo_css test... 09:12:34 INFO - TEST-INFO | started process 22355 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9ZZhEB/profile http://localhost:50135/getInfo.html) 09:12:38 INFO - PROCESS | 22355 | __metrics Screen width/height:1600/1200 09:12:38 INFO - PROCESS | 22355 | colorDepth:24 09:12:38 INFO - PROCESS | 22355 | Browser inner width/height: 1024/697 09:12:38 INFO - PROCESS | 22355 | __metrics 09:12:39 INFO - PROCESS | 22355 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:12:39 INFO - PROCESS | 22355 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:12:39 INFO - PROCESS | 22355 | console.error: PushService: 09:12:39 INFO - PROCESS | 22355 | stateChangeProcessEnqueue: Error transitioning state 09:12:39 INFO - PROCESS | 22355 | UnknownError 09:12:40 INFO - TEST-INFO | 22355: exit 0 09:12:40 INFO - Browser initialized. 09:12:40 INFO - Running cycle 1/1 for dromaeo_css test... 09:12:40 INFO - TEST-INFO | started process 22437 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9ZZhEB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:52 INFO - PROCESS | 22437 | RSS: Main: 153260032 09:12:52 INFO - PROCESS | 22437 | 09:13:43 INFO - PROCESS | 22437 | 1482340423604 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] 09:15:53 INFO - PROCESS | 22437 | Cycle 1(1): loaded http://localhost:50135/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50135/tests/dromaeo/cssquery-ext.html) 09:15:56 INFO - PROCESS | 22437 | RSS: Main: 398237696 09:15:56 INFO - PROCESS | 22437 | 09:18:58 INFO - PROCESS | 22437 | Cycle 1(1): loaded http://localhost:50135/tests/dromaeo/cssquery-ext.html (next: http://localhost:50135/tests/dromaeo/cssquery-jquery.html) 09:18:59 INFO - PROCESS | 22437 | RSS: Main: 255987712 09:18:59 INFO - PROCESS | 22437 | 09:22:01 INFO - PROCESS | 22437 | Cycle 1(1): loaded http://localhost:50135/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50135/tests/dromaeo/cssquery-mootools.html) 09:22:02 INFO - PROCESS | 22437 | RSS: Main: 229543936 09:22:02 INFO - PROCESS | 22437 | 09:25:04 INFO - PROCESS | 22437 | Cycle 1(1): loaded http://localhost:50135/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50135/tests/dromaeo/cssquery-prototype.html) 09:25:04 INFO - PROCESS | 22437 | RSS: Main: 188334080 09:25:04 INFO - PROCESS | 22437 | 09:28:07 INFO - PROCESS | 22437 | Cycle 1(1): loaded http://localhost:50135/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50135/tests/dromaeo/cssquery-yui.html) 09:28:08 INFO - PROCESS | 22437 | RSS: Main: 192507904 09:28:08 INFO - PROCESS | 22437 | 09:31:11 INFO - PROCESS | 22437 | Cycle 1(1): loaded http://localhost:50135/tests/dromaeo/cssquery-yui.html (next: http://localhost:50135/tests/dromaeo/cssquery-dojo.html) 09:31:11 INFO - PROCESS | 22437 | RSS: Main: 283881472 09:31:11 INFO - PROCESS | 22437 | 09:31:11 INFO - PROCESS | 22437 | __start_tp_report 09:31:11 INFO - PROCESS | 22437 | _x_x_mozilla_page_load 09:31:11 INFO - PROCESS | 22437 | _x_x_mozilla_page_load_details 09:31:11 INFO - PROCESS | 22437 | |i|pagename|runs| 09:31:11 INFO - PROCESS | 22437 | |0;dojo.html;2973.7918345715802;2999.865006074725;3003.4743919814027;3013.5027720426133;3040.300730831909;5179.4561571035065;5209;5218.843434696955;5229.241759944805;5274.551663108636;5170.327857378536;5222.791088356461;5222.817201397942;5311.920321195185;5321.228421878825;4743.691660042104;4780.928286075712;4787.401574803158;4791.928121078186;4816.325714399987;4716.080364328957;4761.262004389308;4764.428268607755;4840.903181936377;4851.878703032417;4867.805287788502;4903.583195428393;4903.681260718042;4931.901361972758;4944.530269624398;4669.789859456333;4675.555822196886;4835.371401717754;4844.346013288196;4910.238912968478;4679.017406344672;4684.3910291662205;4711.646626503026;4719.38647975761;4778.450478195017;5163.690178589259;5181.5854731621375;5217.452167522393;5232.607554433394;5245.895082098375;5551.9722401388135;5560.527355174815;5563.66618002917;5564.443555644403;5612.7754889804355;5550.389457159709;5612.382637909826;5618.606697531174;5683.886322273532;5696.715164241771;5306.734663266821;5320.015797077592;5322.228276899906;5323.121684921984;5325.094733895247;5625.606207565513;5631.436856314336;5701.600887937804;5759.539236861041;5773;5473.124300111963;5508.338999320107;5513.779448822002;5513.9448605513435;5519.69641669712;5592.245046918695;5592.468715472024;5596.160575913564;5596.776128954878;5638.379778224392;5463.098588732814;5505.779768809283;5510.118381059011;5569.916451253234;5588.832335029955;5586.329640443091;5586.748596313176;5588.552915766729;5588.832335029955;5593.468620481048;4267.850625228102;4309.741415515079;4310.073334233092;4311.223979683625;4313.331433627793;3899.6100389960775;3906.4335671328067;3908.394198899175;3908.9609103908597;3909.198614284065;5343.599230057761;5343.946560534345;5346.759395827119;5349.759260833272;5350.3312085989255;5599.692016939108;5620.1569764534825;5622.97188514063;5623.943760562423;5628.380878103405;5337;5342.091844386464;5345.438728933484;5356.384015838228;5403.783848646089;4918.729469879191;4920.065187614342;4920.557149856459;4920.852374428773;4968.130577148984;5694.259746232963;5724.4561766632105;5736.254286942671;5759.625624334405;5871.236739223874;5607.74765135578;5610.158476228598;5630.493255607015;5641.26663533738;5741.081426971665;5862.149988251765;5882.23530940975;5887.499562537232;5889.293284805851;5898.8230353089475;5598.10430331145;5600.383957764724;5601.775928962796;5602.85992850182;5603.719814009365;5493.835184944458;5518.33779946409;5526.392096869422;5591.608587399006;5593.356763972195;5480.369757477941;5485.4788795064405;5490.0941344677685;5490.505854473116;5491.505764481216;5348.57211423093;5474.2609747683555;5477.370102438271;5478.671279723229;5493.890122197614;5376.8118115865755;5429.837104886702;5433.239346491416;5433.565314774887;5438.83683489496;5452.727363631882;5453.863653408696;5489.780408783604;5515.669059856422;5538.446155384429;5346.572274218131;5414.675119492843;5421.05131601976;5443.891122177455;5466.508014278575 09:31:11 INFO - PROCESS | 22437 | |1;ext.html;541.7589172818095;556.5519756595944;565.524959034411;569.6439725171768;574.9885002299955;30748.077557673278;33723.66276337236;33776.66223337769;33781;39887;42249;43514.347284790674;43625.563744362546;43668.563314366846;43701;8460.492370457792;8677.826443471127;8681.782955426102;8683.34874884383;8685;7627;7811.4922530035365;8396.874046889301;8498.915010849874;8500.404971651988;10063.798724025515;10300.07299343059;10303.845442318372;10569;10581.36511809294;10221.386716796978;10298.897011029869;10305.536250868692;10318.793624127551;10320;7534.246575342477;7750.961245193795;9788.314817962746;10114.797704045952;10178.083972442515;40139.39790903139;40955;40955.385669214986;40957.59042409567;40994.1801163975;3339.532465454838;3369.9663003369897;3433.1588760753525;3493.0219538529254;3714.739968202227;30355.69644303573;30557.694423055706;30699;30739;30847.2288192797;4008.2584721826265;4067.572904845008;4152.252594532983;4239.173361194568;4339.088791353819;335930;336931;337459;339164;341007;56703.43296567064;57141.42858571444;57198.42801571931;57200;57329.713351432976;58364.12453813196;58666.41333586695;59014.70492647595;59047.40952590505;59139;15369.462068827537;15644.92177539105;15759.29083191259;15769.369225231092;15838.366465341258;11228.943855280671;11243.381614011309;11256.21206515536;11257.324560526395;11277.830832537518;14440.85559144416;14755.188464634548;15705.21473926299;16171.272292746855;16182.491713784382;5252.317198764136;6278.811635650938;6338.809835704844;6485.740570377132;6497.02544618311;900.6352427266968;921.9397692653368;1031.8813336466403;1031.9638812641522;1061.5965932945585;29669;30482.84758576193;30615.693843061283;30863.382732345675;31471.685283146875;1066.4987455895716;1091.7871015152127;1139.800534906388;1149.224273615323;1169.4912712969885;15735.921320393325;16276.430324938807;16338.428155014517;16426.072178346447;16494.75257871133;429173;434899;434927;435118;435619;49545.25682114771;50457.747711261945;53135.46864531383;56592;56802.71598642063;1279.3858947705153;1281.3721276574597;1299.4867027524265;1300.7008388070797;1308.6335825968554;29476.410471790874;31063;31351.686483134876;31403.371932560764;32149.839250803598;50180.74909625429;50743.23885141652;50769.23846142385;50845.745771272384;50923;400670;423694;432807;643376;646214;24870.626940596267;25280.367990800376;25312;25352.098592606;25406.491870162125;41176;41353.172936541705;41447;42325;42357.15285694331;35347;35525.28949420946;35914.28171436609;36049.459258111674;36175.27649447049;35075.29849402946;35393.646063540065;35561;35645.643543564234;35656.4651530222;34979.30041399209;35235.82382088176;35239.14265950772;35504.28991420209;35714.10714732153;30338.54492182575;32602.34795304128;32620;32854.178645534055;32936;24676;24802.25593232134;25543.87228063922;25551.41935740751;25566.74433255718 09:31:11 INFO - PROCESS | 22437 | |2;jquery.html;354.48068579530997;365.2002115367347;380.8517320279357;386.9535655721313;400.9318415869302;14261.215633140173;14566.927165364172;18658.906705466452;18662.160202790874;18709;17302.221400036997;17976;18002;18641.347552835654;18704.906475467636;3778.1310298631192;5030.899382012349;5174.611904107188;5177.818776342829;5183.481651834824;3952.7035472339544;5457.945420545803;5635.690037047961;5685.744141513642;5768.250127483421;5638.210650508911;5764.740586673589;5775.162601422791;5775.942240577582;6210.1616281801835;6058.818235452922;6175.845603859894;6178.227721534809;6180;6187.164732761064;4930.852074437773;5111.693298402108;5188.325517682677;5524.723763811793;5548.334199895998;13007.609771706864;13255.73488530224;13258.270795106266;14974;21383.67924481134;2122.8620139691025;2193.71481707377;2287.576798292321;2605.9478810423875;2648.298200976743;9743.464109473976;9918;10327.32872363294;10418.68743937683;13068.150570212905;1833.7707786526685;1907.4849790556473;1907.980920190808;1933.787283398825;1952.707093935921;78270.60864695697;83665;84287.57856210679;84873;87415.56292218562;10356.637517686995;10463.476826158661;10719.606451161299;10770.192235582206;10829.620963266249;14970.251487425585;14971.251437428085;14981.40074397012;15002.324895379734;15009.624759380884;7787.727429539939;7875.409344299201;7928.801779955432;8100.756977290691;8116.147804480563;7532.736354227576;7535.434842386843;7621.123570789319;7840.882386764203;7857.214278572097;4469.7318160910445;4582.358469814229;4631.796283551313;4667.533246675373;4677.508861569554;1844.1885570349004;1938.234397413014;1951.6487032334317;1956;1959.9612205531091;736.3593673504085;792.7740593930702;864.5158711121666;994.7562847102362;1038.5326603028668;17025.40411085606;17703.803668715846;17978.55053623644;18401.723974140397;18512.629747404975;930.181440332503;950.9239260859254;966.2366730282997;969.8448248280382;1028.83024300991;9714.951425242829;10150.746231344276;10289.382637041801;10290.176785857113;10306.33008854422;392796;395832;404286;404848;420294;63915.680421598525;86110.56944715236;87422.56288718643;89032;92552;955.9474228917476;961.3847137831793;979.0796651147898;982.7150126463389;983.7638966647953;14913.17977511204;15992.920035400213;16577.25402356921;16619.66760664804;16946;32601.510977334885;37118.8144059278;41514;42416.36375454433;42911.570884290755;51960.48039519556;52779;57168.71415642895;57474.712626438275;58844.4115558839;7943.880841787262;8030.919690803017;8175.305099066646;8283.83432331362;8500.70247541333;12037.578684745991;12456.439460224494;13026.54407095747;13051.477940882258;13090.541831035867;10684.946575267073;11174.944125279322;11294.152938529478;11828;11846.519185101863;11169;11295;11375.31748095117;11422.143339249757;11794.351310677652;6278.372162783685;7751.689932402641;11070.612528561462;11487.31076135401;11546.653600392001;7868.803279918048;8035.718749843727;8040.638171282189;8292.12932642088;8342.290905272877;8560.657573696983;10569.097562438836;11122.110231181325;11388.544458221579;11813.409329533317 09:31:11 INFO - PROCESS | 22437 | |3;mootools.html;1409.6546346145192;1430.957071287861;1566.7493201087827;1699.2438364927611;1721.750346199802;8042.356611471083;8564.400491965556;8567.443116197443;8568;8568.785780355494;7037.8592428151405;7220.458465615074;8033.598320084002;8281.710140145082;8351.707690230844;2194.73663160421;2235.675827005083;2235.888205589722;2235.910563577455;2238.183063181943;2247.9775202248015;2261.7851304126084;2328.2898715891647;2332.8483648562874;2338.3218866528687;2209.1826024371007;2232.1071571371417;2232.598132336179;2247.6179049561533;2253.0311965854676;2173.967390489144;2190.44143743346;2192.9232476863235;2195.83531235158;2206.3822129803602;2193;2203.834712396577;2212.623853944833;2215.080741492279;2227.175944900381;8506.404551681384;8506.957465212696;8533.402661813676;8569.528675922822;8602.526861022641;4985.401751789808;5123.743812809382;5182.766775495112;5192.299039629639;5195.272661827347;3033.2416895776055;3053.3740583180393;3207.294395233045;3227.951580726291;3230.48312270038;1008.3848852200152;1019.1655020962238;1032.6282538286212;1042.890496497872;1047.6542740895486;10832.350058996564;11620.767584648343;11629.24409913353;11639.592614258545;11661.242019268806;4296.377025331372;4451.59935605797;4463.575960283768;4484.506704262529;4498.955010449919;9299.674511392204;9306.02286759894;9307.860382094274;9309.627614895464;9315.673951411669;5164.302819119446;5164.586833053347;5167;5167.405748338914;5169.405518365436;4655.5577220164705;4777.95222047782;4809.855704328876;4813.9037219255415;4861.538153875376;3545;3690.261947610435;3702.33357995558;3728.254349130185;3786.5645450773513;1118.4631376939114;1119.9328040317607;1131.1798945764256;1134.6085600467825;1136.198979719296;918.2608000559613;932.2215949681956;943;950.1496160936061;987.0376383026492;5392.946070539245;5579.581531385163;5679.062954612526;5707.1153971134545;5879.3826648202175;421.3300851645898;421.95569465206324;426.3689739186022;428.46822609172335;436.3084511049958;3970.722049456568;4028.3957406389277;4124.216398884202;4206.684498662551;4305.160493703697;10398.116160126323;10577.62978295756;10581.36511809294;10598;10617.893821061845;9674.22606191503;10500.632477863239;10542.789144217073;10589.841152382567;10631.787364252672;7505.099388073357;7860.646270917822;7871.842563148705;7879.251471110236;7883.645235964395;5567.344900761453;5616.297962754656;5689.601727879009;5819.592628516132;5965.194698715618;9604.711858644252;9651.662191823252;9659;9666.709998700051;9670.323077384515;8241.711540096068;8526.190011948855;9671.371360861522;9685.6610018649;9862.55618497155;7312.13540547157;7527.698892044257;7543.585102819397;7635.129393298677;7729.381649467917;8161;8237.929069220963;8302.128276530877;8309.709160179365;8330.250277474814;7053.435725142079;7080.398166155935;7090.07828982229;7167.749128780468;7430.256974302527;7044.401225895856;7091.716331346895;7151.8569628608175;7203.963980180275;7219.530730502501;6473.902891456538;6651.866962660816;6731.720973015112;6788.830279243059;6810.62541560219;4735.715857048588;4740.78666460017;4754.809807607657;4868.780904859219;4884.877878053077;4918.655694101379;4945.406551213878;4968.900621987612;5050.419201791694;5167.638265321391 09:31:11 INFO - PROCESS | 22437 | |4;prototype.html;375.15215612715264;376.753226636553;378.998105009475;380.6897378636413;387.40146473698115;5166.0737839242165;5192;5200.141976573861;5208.609354298428;5240.816571419996;4983.401991760985;5054.115529782292;5147.459516750734;5150.716710580926;5157.922631160535;4037.7684806134043;4075.6943229257777;4081.8366765471847;4135.793210339486;4155.501339839224;4104.8152833122585;4105.856295029675;4113.197926404352;4122.381642753581;4145.585441455861;4086.734362266458;4091.4067460218253;4091.4476545666403;4103.240900433414;4131.65721140629;3946.585608511108;4066.755994640331;4091.2792992659993;4095.795210239506;4099.196353604404;3684.1158122050783;3690.5940346561856;3692.8522859085606;3696.149885526344;3723.832427540767;5008.098542262392;5027.622928280357;5039.546440820343;5054.3176671149295;5054.433125730999;4601.861944141681;4639.334002245157;4661.813527458898;4669.463011753658;4687.22660760974;5330.013947419701;5342.439043900412;5344.7862085516535;5356.143017117242;5362.6782393056155;4083.5285362527984;4092.1176258920636;4099.631033206996;4109.773962432065;4128.793560322002;5347.946520534822;5579.707972296405;5582.748776305036;5593.580481463866;5615.887682246333;3963.8612648557737;3990.521137463523;4004.6596039336982;4015.77913214776;4038.324095500393;4043.73715708478;4044.919101617951;4071.328230841878;4077.2864748668862;4077.2864748668862;4483.820647174077;4620.821690468909;4648.48866624671;4667.463241727177;4669.112868554964;5366;5409.161579955114;5439.48324909133;5440.836774896759;5460.563154947595;1342.8724271194221;1350.581319790868;1352.6415499892537;1360.340234986031;1361.2241022617013;884.3720958119807;939.8402271613842;945.3004776465366;948.0235357581702;950.8098380323825;5110.616703747234;5157.871053223699;5176.922346164811;5194.584433245332;5209.192575150858;5366.651167674088;5374.408815030343;5413.83758487246;5483.506484416422;5488.063885821067;5043.268726034705;5183.688978661293;5214.7914083436235;5227.895442091137;5240.554552862973;2894.2040938742016;2898.884044638233;2903.5499497577907;2904.3320036391747;2905.5786910898223;5541.33503979525;5546.4453554644215;5649.943500565024;5693.544516438675;5714.285714285715;5296.285001524745;5494.670319780826;5515.283013208257;5534.612577119563;5534.987134327661;5672.978863804472;5698.772049118154;5709.400512946081;5720.513756330759;5747.798827041033;5519.31008623922;5543.556515478833;5571.415001424954;5575.68246893098;5580.302462192226;5185.692366896695;5263.315679024621;5436.53789427903;5451.890962180655;5553.333599968029;5726.055200891807;5736.885262294814;5757.453041961007;5787.421257874179;5806.5645076618575;4004.3593025115842;4011.615992482589;4020.597940206073;4050.311447054067;4055.3916912463364;5382.19267109921;5580.637258578179;5601.831945041656;5614.85962850932;5623.971880140573;5473;5478.287822583039;5489.85895500763;5491.835244942658;5512.456512176597;5339.73301334924;5424.5660347172925;5489.85895500747;5496.917546236729;5523.453433038756;5371.355437347544;5380.569554435558;5394.599403265509;5398.784048638167;5399.865003374947;5470.398256191896;5480.205370221375;5488.204210389551;5522.763280871253;5527.419620939743;5250.737463126752;5326.866828329323;5329.307190065267;5373.677579345252;5406.78372865081 09:31:11 INFO - PROCESS | 22437 | |5;yui.html;709.2056896276172;718.4432065149508;745.608555508358;795.0856515007744;796.6295672512281;15585.844141558595;16681;16733.079680617546;16736.497905062854;16746.66506669869;13739.931300343509;13790.793138102912;13817.447302107928;13834.169949802994;13836.654083647914;663.5355251324069;677.1975209376905;769.9422543309246;779.185750890321;795.8686816675241;772.420684486635;786.3512602103259;787.4527203593505;787.6967367563501;787.9566623835686;847.0513025411548;847.8092429203441;848.2069265236993;848.3806821020659;848.4103548034119;705.326413275326;720.2977097330114;805.2350267246081;840.3865178419787;841.8021764885277;603.3634515586054;607.5534482155612;607.9787207447719;607.9787207447719;608.0787606775739;15136.318865650963;15497.07017578949;15503.147326897017;15557.92221038899;15575.610609734735;12513.812292815615;12876.420561074774;13209;13579.728405431935;14197.290135493287;12733.299668518228;12937.4178161982;12996.220226786423;13052;13121;4318.935215971763;4337.956620433787;4392.736435813861;4435.866923992286;4449.644028477714;76298;79638;82436.58781706112;82962.58518707428;85564.14435855624;10846.294990825729;10932.508037138348;10954.945225273823;11143.164262680331;11167;20223.08996095179;20335.084921178583;20360.89819550893;20469.89765051165;20591.691124633144;7623.809404764815;7697.4226932980255;7740.380769538423;7780.766577002586;7886.921130788619;4254.893627659271;4458.888527786831;4555.134524440412;4561.840335588305;4621.353010578522;3474;3586.4799604057266;3593.8203089845924;3597.8021208833243;3610.7291953103622;609.4149616368247;609.978286370328;615.6829232945037;616.9290531588924;631.2172905597026;658.9077529145924;659.9968048566152;705.8164877131983;711.9786406407816;712.8004158835535;8837.333650190129;8875.28997680184;9185.540722963826;9216.430106674849;9793.265505087147;593.1902952469934;618.7865186510646;674.1674032569772;677.9423748981392;680.7991642465485;4086.4891888513935;4272.401863739079;4340.76125813077;4423.977880110579;4441.378207051015;81084.59457702674;84160.57919710362;84259;86290.81362733472;86546.56726716452;73594.26405735982;73839.63080184565;73999.63000185072;74256;74490.62754686191;63322.68338658277;63618.68190659017;64112.35887641157;64773.352266478614;65549;8601.655933762579;8679.218870301704;8691.130886911258;8734.737957861274;8774.225774225773;45087.774561126986;45955.77022114868;46283;46691.29963050489;47233.527664724286;56226.43773562212;61026.38973610207;61034.389656102874;61924;62983.37016629775;11087;11508.424578771195;11546.76906461883;11615.303081815118;12288;11366.43167841588;11918.225315354473;13399.196048237138;13421.731565368442;13859.86140138626;12221.633350999484;12596.55912043074;12715.754636575137;13035.47858085666;13182.60452186436;10963.451827408757;10978.560857565928;10980.505877235384;11133.80288705796;11161.888381116085;11577.421128943688;11874.76250475003;11943.343116128348;12157.635270941886;12279.14046016806;10306.227032972407;10312.535935883054;10404.73988150272;10441.530131144269;10536.315139515908;9825.213982881209;10632.84050739255;10663.946680266548;10703.946480267548;11179.441027948733 09:31:11 INFO - PROCESS | 22437 | __end_tp_report 09:31:11 INFO - PROCESS | 22437 | __start_cc_report 09:31:11 INFO - PROCESS | 22437 | _x_x_mozilla_cycle_collect,2647 09:31:11 INFO - PROCESS | 22437 | __end_cc_report 09:31:11 INFO - PROCESS | 22437 | __startTimestamp1482341471349__endTimestamp 09:31:11 INFO - PROCESS | 22437 | 09:31:11 INFO - PROCESS | 22437 | ------- Summary: start ------- 09:31:11 INFO - PROCESS | 22437 | Number of tests: 6 09:31:11 INFO - PROCESS | 22437 | 09:31:11 INFO - PROCESS | 22437 | [#0] dojo.html Cycles:165 Average:5220.71 Median:5433.57 stddev:582.84 (10.7%) stddev-sans-first:557.33 09:31:11 INFO - PROCESS | 22437 | Values: 2973.8 2999.9 3003.5 3013.5 3040.3 5179.5 5209.0 5218.8 5229.2 5274.6 5170.3 5222.8 5222.8 5311.9 5321.2 4743.7 4780.9 4787.4 4791.9 4816.3 4716.1 4761.3 4764.4 4840.9 4851.9 4867.8 4903.6 4903.7 4931.9 4944.5 4669.8 4675.6 4835.4 4844.3 4910.2 4679.0 4684.4 4711.6 4719.4 4778.5 5163.7 5181.6 5217.5 5232.6 5245.9 5552.0 5560.5 5563.7 5564.4 5612.8 5550.4 5612.4 5618.6 5683.9 5696.7 5306.7 5320.0 5322.2 5323.1 5325.1 5625.6 5631.4 5701.6 5759.5 5773.0 5473.1 5508.3 5513.8 5513.9 5519.7 5592.2 5592.5 5596.2 5596.8 5638.4 5463.1 5505.8 5510.1 5569.9 5588.8 5586.3 5586.7 5588.6 5588.8 5593.5 4267.9 4309.7 4310.1 4311.2 4313.3 3899.6 3906.4 3908.4 3909.0 3909.2 5343.6 5343.9 5346.8 5349.8 5350.3 5599.7 5620.2 5623.0 5623.9 5628.4 5337.0 5342.1 5345.4 5356.4 5403.8 4918.7 4920.1 4920.6 4920.9 4968.1 5694.3 5724.5 5736.3 5759.6 5871.2 5607.7 5610.2 5630.5 5641.3 5741.1 5862.1 5882.2 5887.5 5889.3 5898.8 5598.1 5600.4 5601.8 5602.9 5603.7 5493.8 5518.3 5526.4 5591.6 5593.4 5480.4 5485.5 5490.1 5490.5 5491.5 5348.6 5474.3 5477.4 5478.7 5493.9 5376.8 5429.8 5433.2 5433.6 5438.8 5452.7 5453.9 5489.8 5515.7 5538.4 5346.6 5414.7 5421.1 5443.9 5466.5 09:31:11 INFO - PROCESS | 22437 | 09:31:11 INFO - PROCESS | 22437 | [#1] ext.html Cycles:180 Average:57396.07 Median:35237.48 stddev:116366.37 (330.2%) stddev-sans-first:116614.51 09:31:11 INFO - PROCESS | 22437 | Values: 541.8 556.6 565.5 569.6 575.0 30748.1 33723.7 33776.7 33781.0 39887.0 42249.0 43514.3 43625.6 43668.6 43701.0 8460.5 8677.8 8681.8 8683.3 8685.0 7627.0 7811.5 8396.9 8498.9 8500.4 10063.8 10300.1 10303.8 10569.0 10581.4 10221.4 10298.9 10305.5 10318.8 10320.0 7534.2 7751.0 9788.3 10114.8 10178.1 40139.4 40955.0 40955.4 40957.6 40994.2 3339.5 3370.0 3433.2 3493.0 3714.7 30355.7 30557.7 30699.0 30739.0 30847.2 4008.3 4067.6 4152.3 4239.2 4339.1 335930.0 336931.0 337459.0 339164.0 341007.0 56703.4 57141.4 57198.4 57200.0 57329.7 58364.1 58666.4 59014.7 59047.4 59139.0 15369.5 15644.9 15759.3 15769.4 15838.4 11228.9 11243.4 11256.2 11257.3 11277.8 14440.9 14755.2 15705.2 16171.3 16182.5 5252.3 6278.8 6338.8 6485.7 6497.0 900.6 921.9 1031.9 1032.0 1061.6 29669.0 30482.8 30615.7 30863.4 31471.7 1066.5 1091.8 1139.8 1149.2 1169.5 15735.9 16276.4 16338.4 16426.1 16494.8 429173.0 434899.0 434927.0 435118.0 435619.0 49545.3 50457.7 53135.5 56592.0 56802.7 1279.4 1281.4 1299.5 1300.7 1308.6 29476.4 31063.0 31351.7 31403.4 32149.8 50180.7 50743.2 50769.2 50845.7 50923.0 400670.0 423694.0 432807.0 643376.0 646214.0 24870.6 25280.4 25312.0 25352.1 25406.5 41176.0 41353.2 41447.0 42325.0 42357.2 35347.0 35525.3 35914.3 36049.5 36175.3 35075.3 35393.6 35561.0 35645.6 35656.5 34979.3 35235.8 35239.1 35504.3 35714.1 30338.5 32602.3 32620.0 32854.2 32936.0 24676.0 24802.3 25543.9 25551.4 25566.7 09:31:11 INFO - PROCESS | 22437 | 09:31:11 INFO - PROCESS | 22437 | [#2] jquery.html Cycles:180 Average:25726.46 Median:198116.47 stddev:67073.01 (33.9%) stddev-sans-first:67234.12 09:31:11 INFO - PROCESS | 22437 | Values: 354.5 365.2 380.9 387.0 400.9 14261.2 14566.9 18658.9 18662.2 18709.0 17302.2 17976.0 18002.0 18641.3 18704.9 3778.1 5030.9 5174.6 5177.8 5183.5 3952.7 5457.9 5635.7 5685.7 5768.3 5638.2 5764.7 5775.2 5775.9 6210.2 6058.8 6175.8 6178.2 6180.0 6187.2 4930.9 5111.7 5188.3 5524.7 5548.3 13007.6 13255.7 13258.3 14974.0 21383.7 2122.9 2193.7 2287.6 2605.9 2648.3 9743.5 9918.0 10327.3 10418.7 13068.2 1833.8 1907.5 1908.0 1933.8 1952.7 78270.6 83665.0 84287.6 84873.0 87415.6 10356.6 10463.5 10719.6 10770.2 10829.6 14970.3 14971.3 14981.4 15002.3 15009.6 7787.7 7875.4 7928.8 8100.8 8116.1 7532.7 7535.4 7621.1 7840.9 7857.2 4469.7 4582.4 4631.8 4667.5 4677.5 1844.2 1938.2 1951.6 1956.0 1960.0 736.4 792.8 864.5 994.8 1038.5 17025.4 17703.8 17978.6 18401.7 18512.6 930.2 950.9 966.2 969.8 1028.8 9715.0 10150.7 10289.4 10290.2 10306.3 392796.0 395832.0 404286.0 404848.0 420294.0 63915.7 86110.6 87422.6 89032.0 92552.0 955.9 961.4 979.1 982.7 983.8 14913.2 15992.9 16577.3 16619.7 16946.0 32601.5 37118.8 41514.0 42416.4 42911.6 51960.5 52779.0 57168.7 57474.7 58844.4 7943.9 8030.9 8175.3 8283.8 8500.7 12037.6 12456.4 13026.5 13051.5 13090.5 10684.9 11174.9 11294.2 11828.0 11846.5 11169.0 11295.0 11375.3 11422.1 11794.4 6278.4 7751.7 11070.6 11487.3 11546.7 7868.8 8035.7 8040.6 8292.1 8342.3 8560.7 10569.1 11122.1 11388.5 11813.4 09:31:11 INFO - PROCESS | 22437 | 09:31:11 INFO - PROCESS | 22437 | [#3] mootools.html Cycles:180 Average:5497.93 Median:4957.15 stddev:3112.80 (62.8%) stddev-sans-first:3106.37 09:31:11 INFO - PROCESS | 22437 | Values: 1409.7 1431.0 1566.7 1699.2 1721.8 8042.4 8564.4 8567.4 8568.0 8568.8 7037.9 7220.5 8033.6 8281.7 8351.7 2194.7 2235.7 2235.9 2235.9 2238.2 2248.0 2261.8 2328.3 2332.8 2338.3 2209.2 2232.1 2232.6 2247.6 2253.0 2174.0 2190.4 2192.9 2195.8 2206.4 2193.0 2203.8 2212.6 2215.1 2227.2 8506.4 8507.0 8533.4 8569.5 8602.5 4985.4 5123.7 5182.8 5192.3 5195.3 3033.2 3053.4 3207.3 3228.0 3230.5 1008.4 1019.2 1032.6 1042.9 1047.7 10832.4 11620.8 11629.2 11639.6 11661.2 4296.4 4451.6 4463.6 4484.5 4499.0 9299.7 9306.0 9307.9 9309.6 9315.7 5164.3 5164.6 5167.0 5167.4 5169.4 4655.6 4778.0 4809.9 4813.9 4861.5 3545.0 3690.3 3702.3 3728.3 3786.6 1118.5 1119.9 1131.2 1134.6 1136.2 918.3 932.2 943.0 950.1 987.0 5392.9 5579.6 5679.1 5707.1 5879.4 421.3 422.0 426.4 428.5 436.3 3970.7 4028.4 4124.2 4206.7 4305.2 10398.1 10577.6 10581.4 10598.0 10617.9 9674.2 10500.6 10542.8 10589.8 10631.8 7505.1 7860.6 7871.8 7879.3 7883.6 5567.3 5616.3 5689.6 5819.6 5965.2 9604.7 9651.7 9659.0 9666.7 9670.3 8241.7 8526.2 9671.4 9685.7 9862.6 7312.1 7527.7 7543.6 7635.1 7729.4 8161.0 8237.9 8302.1 8309.7 8330.3 7053.4 7080.4 7090.1 7167.7 7430.3 7044.4 7091.7 7151.9 7204.0 7219.5 6473.9 6651.9 6731.7 6788.8 6810.6 4735.7 4740.8 4754.8 4868.8 4884.9 4918.7 4945.4 4968.9 5050.4 5167.6 09:31:11 INFO - PROCESS | 22437 | 09:31:11 INFO - PROCESS | 22437 | [#4] prototype.html Cycles:180 Average:4577.22 Median:5208.90 stddev:1320.57 (25.4%) stddev-sans-first:1286.06 09:31:11 INFO - PROCESS | 22437 | Values: 375.2 376.8 379.0 380.7 387.4 5166.1 5192.0 5200.1 5208.6 5240.8 4983.4 5054.1 5147.5 5150.7 5157.9 4037.8 4075.7 4081.8 4135.8 4155.5 4104.8 4105.9 4113.2 4122.4 4145.6 4086.7 4091.4 4091.4 4103.2 4131.7 3946.6 4066.8 4091.3 4095.8 4099.2 3684.1 3690.6 3692.9 3696.1 3723.8 5008.1 5027.6 5039.5 5054.3 5054.4 4601.9 4639.3 4661.8 4669.5 4687.2 5330.0 5342.4 5344.8 5356.1 5362.7 4083.5 4092.1 4099.6 4109.8 4128.8 5347.9 5579.7 5582.7 5593.6 5615.9 3963.9 3990.5 4004.7 4015.8 4038.3 4043.7 4044.9 4071.3 4077.3 4077.3 4483.8 4620.8 4648.5 4667.5 4669.1 5366.0 5409.2 5439.5 5440.8 5460.6 1342.9 1350.6 1352.6 1360.3 1361.2 884.4 939.8 945.3 948.0 950.8 5110.6 5157.9 5176.9 5194.6 5209.2 5366.7 5374.4 5413.8 5483.5 5488.1 5043.3 5183.7 5214.8 5227.9 5240.6 2894.2 2898.9 2903.5 2904.3 2905.6 5541.3 5546.4 5649.9 5693.5 5714.3 5296.3 5494.7 5515.3 5534.6 5535.0 5673.0 5698.8 5709.4 5720.5 5747.8 5519.3 5543.6 5571.4 5575.7 5580.3 5185.7 5263.3 5436.5 5451.9 5553.3 5726.1 5736.9 5757.5 5787.4 5806.6 4004.4 4011.6 4020.6 4050.3 4055.4 5382.2 5580.6 5601.8 5614.9 5624.0 5473.0 5478.3 5489.9 5491.8 5512.5 5339.7 5424.6 5489.9 5496.9 5523.5 5371.4 5380.6 5394.6 5398.8 5399.9 5470.4 5480.2 5488.2 5522.8 5527.4 5250.7 5326.9 5329.3 5373.7 5406.8 09:31:11 INFO - PROCESS | 22437 | 09:31:11 INFO - PROCESS | 22437 | [#5] yui.html Cycles:180 Average:17901.93 Median:25452.18 stddev:23927.91 (94.0%) stddev-sans-first:23960.21 09:31:11 INFO - PROCESS | 22437 | Values: 709.2 718.4 745.6 795.1 796.6 15585.8 16681.0 16733.1 16736.5 16746.7 13739.9 13790.8 13817.4 13834.2 13836.7 663.5 677.2 769.9 779.2 795.9 772.4 786.4 787.5 787.7 788.0 847.1 847.8 848.2 848.4 848.4 705.3 720.3 805.2 840.4 841.8 603.4 607.6 608.0 608.0 608.1 15136.3 15497.1 15503.1 15557.9 15575.6 12513.8 12876.4 13209.0 13579.7 14197.3 12733.3 12937.4 12996.2 13052.0 13121.0 4318.9 4338.0 4392.7 4435.9 4449.6 76298.0 79638.0 82436.6 82962.6 85564.1 10846.3 10932.5 10954.9 11143.2 11167.0 20223.1 20335.1 20360.9 20469.9 20591.7 7623.8 7697.4 7740.4 7780.8 7886.9 4254.9 4458.9 4555.1 4561.8 4621.4 3474.0 3586.5 3593.8 3597.8 3610.7 609.4 610.0 615.7 616.9 631.2 658.9 660.0 705.8 712.0 712.8 8837.3 8875.3 9185.5 9216.4 9793.3 593.2 618.8 674.2 677.9 680.8 4086.5 4272.4 4340.8 4424.0 4441.4 81084.6 84160.6 84259.0 86290.8 86546.6 73594.3 73839.6 73999.6 74256.0 74490.6 63322.7 63618.7 64112.4 64773.4 65549.0 8601.7 8679.2 8691.1 8734.7 8774.2 45087.8 45955.8 46283.0 46691.3 47233.5 56226.4 61026.4 61034.4 61924.0 62983.4 11087.0 11508.4 11546.8 11615.3 12288.0 11366.4 11918.2 13399.2 13421.7 13859.9 12221.6 12596.6 12715.8 13035.5 13182.6 10963.5 10978.6 10980.5 11133.8 11161.9 11577.4 11874.8 11943.3 12157.6 12279.1 10306.2 10312.5 10404.7 10441.5 10536.3 9825.2 10632.8 10663.9 10703.9 11179.4 09:31:11 INFO - PROCESS | 22437 | -------- Summary: end -------- 09:31:11 INFO - PROCESS | 22437 | 09:31:12 INFO - TEST-INFO | 22437: exit 0 09:31:12 INFO - TEST-OK | dromaeo_css | took 1117297ms 09:31:12 INFO - TEST-START | kraken 09:31:12 INFO - operating with platform_type : linux_ 09:31:12 INFO - Initialising browser for kraken test... 09:31:12 INFO - TEST-INFO | started process 22742 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMdyuWl/profile http://localhost:50135/getInfo.html) 09:31:15 INFO - PROCESS | 22742 | __metrics Screen width/height:1600/1200 09:31:15 INFO - PROCESS | 22742 | colorDepth:24 09:31:15 INFO - PROCESS | 22742 | Browser inner width/height: 1024/697 09:31:15 INFO - PROCESS | 22742 | __metrics 09:31:16 INFO - PROCESS | 22742 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:31:16 INFO - PROCESS | 22742 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:31:17 INFO - PROCESS | 22742 | console.error: PushService: 09:31:17 INFO - PROCESS | 22742 | stateChangeProcessEnqueue: Error transitioning state 09:31:17 INFO - PROCESS | 22742 | UnknownError 09:31:17 INFO - TEST-INFO | 22742: exit 0 09:31:17 INFO - Browser initialized. 09:31:17 INFO - Running cycle 1/1 for kraken test... 09:31:17 INFO - TEST-INFO | started process 22826 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMdyuWl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:31:29 INFO - PROCESS | 22826 | RSS: Main: 153214976 09:31:29 INFO - PROCESS | 22826 | 09:31:32 INFO - PROCESS | 22826 | Cycle 1(1): loaded http://localhost:50135/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50135/tests/kraken/driver.html?testName=audio-beat-detection) 09:31:32 INFO - PROCESS | 22826 | RSS: Main: 205873152 09:31:32 INFO - PROCESS | 22826 | 09:31:35 INFO - PROCESS | 22826 | Cycle 1(1): loaded http://localhost:50135/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50135/tests/kraken/driver.html?testName=audio-dft) 09:31:36 INFO - PROCESS | 22826 | RSS: Main: 185204736 09:31:36 INFO - PROCESS | 22826 | 09:31:39 INFO - PROCESS | 22826 | Cycle 1(1): loaded http://localhost:50135/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50135/tests/kraken/driver.html?testName=audio-fft) 09:31:39 INFO - PROCESS | 22826 | RSS: Main: 266731520 09:31:39 INFO - PROCESS | 22826 | 09:31:41 INFO - PROCESS | 22826 | Cycle 1(1): loaded http://localhost:50135/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50135/tests/kraken/driver.html?testName=audio-oscillator) 09:31:42 INFO - PROCESS | 22826 | RSS: Main: 178675712 09:31:42 INFO - PROCESS | 22826 | 09:31:44 INFO - PROCESS | 22826 | Cycle 1(1): loaded http://localhost:50135/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50135/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:31:45 INFO - PROCESS | 22826 | RSS: Main: 182751232 09:31:45 INFO - PROCESS | 22826 | 09:31:48 INFO - PROCESS | 22826 | Cycle 1(1): loaded http://localhost:50135/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50135/tests/kraken/driver.html?testName=imaging-darkroom) 09:31:48 INFO - PROCESS | 22826 | RSS: Main: 221818880 09:31:48 INFO - PROCESS | 22826 | 09:31:52 INFO - PROCESS | 22826 | Cycle 1(1): loaded http://localhost:50135/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50135/tests/kraken/driver.html?testName=imaging-desaturate) 09:31:52 INFO - PROCESS | 22826 | RSS: Main: 221253632 09:31:52 INFO - PROCESS | 22826 | 09:31:55 INFO - PROCESS | 22826 | Cycle 1(1): loaded http://localhost:50135/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50135/tests/kraken/driver.html?testName=json-parse-financial) 09:31:56 INFO - PROCESS | 22826 | RSS: Main: 220487680 09:31:56 INFO - PROCESS | 22826 | 09:31:57 INFO - PROCESS | 22826 | Cycle 1(1): loaded http://localhost:50135/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50135/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:31:58 INFO - PROCESS | 22826 | RSS: Main: 182218752 09:31:58 INFO - PROCESS | 22826 | 09:32:00 INFO - PROCESS | 22826 | Cycle 1(1): loaded http://localhost:50135/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50135/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:32:00 INFO - PROCESS | 22826 | RSS: Main: 189636608 09:32:00 INFO - PROCESS | 22826 | 09:32:02 INFO - PROCESS | 22826 | Cycle 1(1): loaded http://localhost:50135/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50135/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:32:03 INFO - PROCESS | 22826 | RSS: Main: 198410240 09:32:03 INFO - PROCESS | 22826 | 09:32:05 INFO - PROCESS | 22826 | Cycle 1(1): loaded http://localhost:50135/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50135/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:32:06 INFO - PROCESS | 22826 | RSS: Main: 207388672 09:32:06 INFO - PROCESS | 22826 | 09:32:09 INFO - PROCESS | 22826 | Cycle 1(1): loaded http://localhost:50135/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50135/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:32:10 INFO - PROCESS | 22826 | RSS: Main: 204169216 09:32:10 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | Cycle 1(1): loaded http://localhost:50135/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50135/tests/kraken/driver.html?testName=ai-astar) 09:32:11 INFO - PROCESS | 22826 | RSS: Main: 255770624 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | __start_tp_report 09:32:11 INFO - PROCESS | 22826 | _x_x_mozilla_page_load 09:32:11 INFO - PROCESS | 22826 | _x_x_mozilla_page_load_details 09:32:11 INFO - PROCESS | 22826 | |i|pagename|runs| 09:32:11 INFO - PROCESS | 22826 | |0;ai-astar;113;112;114;114;113;114;111;111;111;113 09:32:11 INFO - PROCESS | 22826 | |1;audio-beat-detection;150;121;121;157;124;127;126;124;145;120 09:32:11 INFO - PROCESS | 22826 | |2;audio-dft;161;162;162;161;162;162;162;163;161;162 09:32:11 INFO - PROCESS | 22826 | |3;audio-fft;75;75;76;113;78;75;75;76;91;76 09:32:11 INFO - PROCESS | 22826 | |4;audio-oscillator;83;84;109;83;84;84;95;85;83;109 09:32:11 INFO - PROCESS | 22826 | |5;imaging-gaussian-blur;118;117;117;117;117;116;117;117;116;117 09:32:11 INFO - PROCESS | 22826 | |6;imaging-darkroom;146;147;146;145;146;147;146;146;146;147 09:32:11 INFO - PROCESS | 22826 | |7;imaging-desaturate;96;96;96;96;96;95;95;95;96;95 09:32:11 INFO - PROCESS | 22826 | |8;json-parse-financial;56;60;53;54;54;55;55;55;55;55 09:32:11 INFO - PROCESS | 22826 | |9;json-stringify-tinderbox;62;64;63;63;64;63;63;62;62;64 09:32:11 INFO - PROCESS | 22826 | |10;stanford-crypto-aes;80;79;79;79;86;79;78;89;79;78 09:32:11 INFO - PROCESS | 22826 | |11;stanford-crypto-ccm;133;131;137;126;127;130;133;125;163;140 09:32:11 INFO - PROCESS | 22826 | |12;stanford-crypto-pbkdf2;164;163;164;168;165;161;170;168;164;165 09:32:11 INFO - PROCESS | 22826 | |13;stanford-crypto-sha256-iterative;62;67;63;68;63;72;62;62;61;62 09:32:11 INFO - PROCESS | 22826 | __end_tp_report 09:32:11 INFO - PROCESS | 22826 | __start_cc_report 09:32:11 INFO - PROCESS | 22826 | _x_x_mozilla_cycle_collect,1676 09:32:11 INFO - PROCESS | 22826 | __end_cc_report 09:32:11 INFO - PROCESS | 22826 | __startTimestamp1482341531945__endTimestamp 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | ------- Summary: start ------- 09:32:11 INFO - PROCESS | 22826 | Number of tests: 14 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | [#0] ai-astar Cycles:10 Average:112.60 Median:113.00 stddev:1.26 (1.1%) stddev-sans-first:1.33 09:32:11 INFO - PROCESS | 22826 | Values: 113.0 112.0 114.0 114.0 113.0 114.0 111.0 111.0 111.0 113.0 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | [#1] audio-beat-detection Cycles:10 Average:131.50 Median:126.50 stddev:13.71 (10.8%) stddev-sans-first:12.80 09:32:11 INFO - PROCESS | 22826 | Values: 150.0 121.0 121.0 157.0 124.0 127.0 126.0 124.0 145.0 120.0 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 09:32:11 INFO - PROCESS | 22826 | Values: 161.0 162.0 162.0 161.0 162.0 162.0 162.0 163.0 161.0 162.0 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | [#3] audio-fft Cycles:10 Average:81.00 Median:76.00 stddev:12.26 (16.1%) stddev-sans-first:12.81 09:32:11 INFO - PROCESS | 22826 | Values: 75.0 75.0 76.0 113.0 78.0 75.0 75.0 76.0 91.0 76.0 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:84.00 stddev:10.68 (12.7%) stddev-sans-first:11.03 09:32:11 INFO - PROCESS | 22826 | Values: 83.0 84.0 109.0 83.0 84.0 84.0 95.0 85.0 83.0 109.0 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 09:32:11 INFO - PROCESS | 22826 | Values: 118.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 09:32:11 INFO - PROCESS | 22826 | Values: 146.0 147.0 146.0 145.0 146.0 147.0 146.0 146.0 146.0 147.0 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 09:32:11 INFO - PROCESS | 22826 | Values: 96.0 96.0 96.0 96.0 96.0 95.0 95.0 95.0 96.0 95.0 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.00 stddev:1.87 (3.4%) stddev-sans-first:1.96 09:32:11 INFO - PROCESS | 22826 | Values: 56.0 60.0 53.0 54.0 54.0 55.0 55.0 55.0 55.0 55.0 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | [#9] json-stringify-tinderbox Cycles:10 Average:63.00 Median:63.00 stddev:0.82 (1.3%) stddev-sans-first:0.78 09:32:11 INFO - PROCESS | 22826 | Values: 62.0 64.0 63.0 63.0 64.0 63.0 63.0 62.0 62.0 64.0 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:79.00 stddev:3.75 (4.7%) stddev-sans-first:3.97 09:32:11 INFO - PROCESS | 22826 | Values: 80.0 79.0 79.0 79.0 86.0 79.0 78.0 89.0 79.0 78.0 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | [#11] stanford-crypto-ccm Cycles:10 Average:134.50 Median:133.00 stddev:11.08 (8.3%) stddev-sans-first:11.74 09:32:11 INFO - PROCESS | 22826 | Values: 133.0 131.0 137.0 126.0 127.0 130.0 133.0 125.0 163.0 140.0 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:165.00 stddev:2.70 (1.6%) stddev-sans-first:2.83 09:32:11 INFO - PROCESS | 22826 | Values: 164.0 163.0 164.0 168.0 165.0 161.0 170.0 168.0 164.0 165.0 09:32:11 INFO - PROCESS | 22826 | 09:32:11 INFO - PROCESS | 22826 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:63.00 stddev:3.58 (5.7%) stddev-sans-first:3.71 09:32:11 INFO - PROCESS | 22826 | Values: 62.0 67.0 63.0 68.0 63.0 72.0 62.0 62.0 61.0 62.0 09:32:11 INFO - PROCESS | 22826 | -------- Summary: end -------- 09:32:11 INFO - PROCESS | 22826 | 09:32:12 INFO - TEST-INFO | 22826: exit 0 09:32:12 INFO - TEST-OK | kraken | took 60828ms 09:32:12 INFO - SUITE-END | took 1178s 09:32:13 INFO - Completed test suite (00:19:38) 09:32:13 INFO - javascript benchmark 09:32:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2973.7918345715802, 2999.865006074725, 3003.4743919814027, 3013.5027720426133, 3040.300730831909, 5179.4561571035065, 5209.0, 5218.843434696955, 5229.241759944805, 5274.551663108636, 5170.327857378536, 5222.791088356461, 5222.817201397942, 5311.920321195185, 5321.228421878825, 4743.691660042104, 4780.928286075712, 4787.401574803158, 4791.928121078186, 4816.325714399987, 4716.080364328957, 4761.262004389308, 4764.428268607755, 4840.903181936377, 4851.878703032417, 4867.805287788502, 4903.583195428393, 4903.681260718042, 4931.901361972758, 4944.530269624398, 4669.789859456333, 4675.555822196886, 4835.371401717754, 4844.346013288196, 4910.238912968478, 4679.017406344672, 4684.3910291662205, 4711.646626503026, 4719.38647975761, 4778.450478195017, 5163.690178589259, 5181.5854731621375, 5217.452167522393, 5232.607554433394, 5245.895082098375, 5551.9722401388135, 5560.527355174815, 5563.66618002917, 5564.443555644403, 5612.7754889804355, 5550.389457159709, 5612.382637909826, 5618.606697531174, 5683.886322273532, 5696.715164241771, 5306.734663266821, 5320.015797077592, 5322.228276899906, 5323.121684921984, 5325.094733895247, 5625.606207565513, 5631.436856314336, 5701.600887937804, 5759.539236861041, 5773.0, 5473.124300111963, 5508.338999320107, 5513.779448822002, 5513.9448605513435, 5519.69641669712, 5592.245046918695, 5592.468715472024, 5596.160575913564, 5596.776128954878, 5638.379778224392, 5463.098588732814, 5505.779768809283, 5510.118381059011, 5569.916451253234, 5588.832335029955, 5586.329640443091, 5586.748596313176, 5588.552915766729, 5588.832335029955, 5593.468620481048, 4267.850625228102, 4309.741415515079, 4310.073334233092, 4311.223979683625, 4313.331433627793, 3899.6100389960775, 3906.4335671328067, 3908.394198899175, 3908.9609103908597, 3909.198614284065, 5343.599230057761, 5343.946560534345, 5346.759395827119, 5349.759260833272, 5350.3312085989255, 5599.692016939108, 5620.1569764534825, 5622.97188514063, 5623.943760562423, 5628.380878103405, 5337.0, 5342.091844386464, 5345.438728933484, 5356.384015838228, 5403.783848646089, 4918.729469879191, 4920.065187614342, 4920.557149856459, 4920.852374428773, 4968.130577148984, 5694.259746232963, 5724.4561766632105, 5736.254286942671, 5759.625624334405, 5871.236739223874, 5607.74765135578, 5610.158476228598, 5630.493255607015, 5641.26663533738, 5741.081426971665, 5862.149988251765, 5882.23530940975, 5887.499562537232, 5889.293284805851, 5898.8230353089475, 5598.10430331145, 5600.383957764724, 5601.775928962796, 5602.85992850182, 5603.719814009365, 5493.835184944458, 5518.33779946409, 5526.392096869422, 5591.608587399006, 5593.356763972195, 5480.369757477941, 5485.4788795064405, 5490.0941344677685, 5490.505854473116, 5491.505764481216, 5348.57211423093, 5474.2609747683555, 5477.370102438271, 5478.671279723229, 5493.890122197614, 5376.8118115865755, 5429.837104886702, 5433.239346491416, 5433.565314774887, 5438.83683489496, 5452.727363631882, 5453.863653408696, 5489.780408783604, 5515.669059856422, 5538.446155384429, 5346.572274218131, 5414.675119492843, 5421.05131601976, 5443.891122177455, 5466.508014278575], "value": 5181.533429333239, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.7589172818095, 556.5519756595944, 565.524959034411, 569.6439725171768, 574.9885002299955, 30748.077557673278, 33723.66276337236, 33776.66223337769, 33781.0, 39887.0, 42249.0, 43514.347284790674, 43625.563744362546, 43668.563314366846, 43701.0, 8460.492370457792, 8677.826443471127, 8681.782955426102, 8683.34874884383, 8685.0, 7627.0, 7811.4922530035365, 8396.874046889301, 8498.915010849874, 8500.404971651988, 10063.798724025515, 10300.07299343059, 10303.845442318372, 10569.0, 10581.36511809294, 10221.386716796978, 10298.897011029869, 10305.536250868692, 10318.793624127551, 10320.0, 7534.246575342477, 7750.961245193795, 9788.314817962746, 10114.797704045952, 10178.083972442515, 40139.39790903139, 40955.0, 40955.385669214986, 40957.59042409567, 40994.1801163975, 3339.532465454838, 3369.9663003369897, 3433.1588760753525, 3493.0219538529254, 3714.739968202227, 30355.69644303573, 30557.694423055706, 30699.0, 30739.0, 30847.2288192797, 4008.2584721826265, 4067.572904845008, 4152.252594532983, 4239.173361194568, 4339.088791353819, 335930.0, 336931.0, 337459.0, 339164.0, 341007.0, 56703.43296567064, 57141.42858571444, 57198.42801571931, 57200.0, 57329.713351432976, 58364.12453813196, 58666.41333586695, 59014.70492647595, 59047.40952590505, 59139.0, 15369.462068827537, 15644.92177539105, 15759.29083191259, 15769.369225231092, 15838.366465341258, 11228.943855280671, 11243.381614011309, 11256.21206515536, 11257.324560526395, 11277.830832537518, 14440.85559144416, 14755.188464634548, 15705.21473926299, 16171.272292746855, 16182.491713784382, 5252.317198764136, 6278.811635650938, 6338.809835704844, 6485.740570377132, 6497.02544618311, 900.6352427266968, 921.9397692653368, 1031.8813336466403, 1031.9638812641522, 1061.5965932945585, 29669.0, 30482.84758576193, 30615.693843061283, 30863.382732345675, 31471.685283146875, 1066.4987455895716, 1091.7871015152127, 1139.800534906388, 1149.224273615323, 1169.4912712969885, 15735.921320393325, 16276.430324938807, 16338.428155014517, 16426.072178346447, 16494.75257871133, 429173.0, 434899.0, 434927.0, 435118.0, 435619.0, 49545.25682114771, 50457.747711261945, 53135.46864531383, 56592.0, 56802.71598642063, 1279.3858947705153, 1281.3721276574597, 1299.4867027524265, 1300.7008388070797, 1308.6335825968554, 29476.410471790874, 31063.0, 31351.686483134876, 31403.371932560764, 32149.839250803598, 50180.74909625429, 50743.23885141652, 50769.23846142385, 50845.745771272384, 50923.0, 400670.0, 423694.0, 432807.0, 643376.0, 646214.0, 24870.626940596267, 25280.367990800376, 25312.0, 25352.098592606, 25406.491870162125, 41176.0, 41353.172936541705, 41447.0, 42325.0, 42357.15285694331, 35347.0, 35525.28949420946, 35914.28171436609, 36049.459258111674, 36175.27649447049, 35075.29849402946, 35393.646063540065, 35561.0, 35645.643543564234, 35656.4651530222, 34979.30041399209, 35235.82382088176, 35239.14265950772, 35504.28991420209, 35714.10714732153, 30338.54492182575, 32602.34795304128, 32620.0, 32854.178645534055, 32936.0, 24676.0, 24802.25593232134, 25543.87228063922, 25551.41935740751, 25566.74433255718], "value": 18964.382187531526, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.48068579530997, 365.2002115367347, 380.8517320279357, 386.9535655721313, 400.9318415869302, 14261.215633140173, 14566.927165364172, 18658.906705466452, 18662.160202790874, 18709.0, 17302.221400036997, 17976.0, 18002.0, 18641.347552835654, 18704.906475467636, 3778.1310298631192, 5030.899382012349, 5174.611904107188, 5177.818776342829, 5183.481651834824, 3952.7035472339544, 5457.945420545803, 5635.690037047961, 5685.744141513642, 5768.250127483421, 5638.210650508911, 5764.740586673589, 5775.162601422791, 5775.942240577582, 6210.1616281801835, 6058.818235452922, 6175.845603859894, 6178.227721534809, 6180.0, 6187.164732761064, 4930.852074437773, 5111.693298402108, 5188.325517682677, 5524.723763811793, 5548.334199895998, 13007.609771706864, 13255.73488530224, 13258.270795106266, 14974.0, 21383.67924481134, 2122.8620139691025, 2193.71481707377, 2287.576798292321, 2605.9478810423875, 2648.298200976743, 9743.464109473976, 9918.0, 10327.32872363294, 10418.68743937683, 13068.150570212905, 1833.7707786526685, 1907.4849790556473, 1907.980920190808, 1933.787283398825, 1952.707093935921, 78270.60864695697, 83665.0, 84287.57856210679, 84873.0, 87415.56292218562, 10356.637517686995, 10463.476826158661, 10719.606451161299, 10770.192235582206, 10829.620963266249, 14970.251487425585, 14971.251437428085, 14981.40074397012, 15002.324895379734, 15009.624759380884, 7787.727429539939, 7875.409344299201, 7928.801779955432, 8100.756977290691, 8116.147804480563, 7532.736354227576, 7535.434842386843, 7621.123570789319, 7840.882386764203, 7857.214278572097, 4469.7318160910445, 4582.358469814229, 4631.796283551313, 4667.533246675373, 4677.508861569554, 1844.1885570349004, 1938.234397413014, 1951.6487032334317, 1956.0, 1959.9612205531091, 736.3593673504085, 792.7740593930702, 864.5158711121666, 994.7562847102362, 1038.5326603028668, 17025.40411085606, 17703.803668715846, 17978.55053623644, 18401.723974140397, 18512.629747404975, 930.181440332503, 950.9239260859254, 966.2366730282997, 969.8448248280382, 1028.83024300991, 9714.951425242829, 10150.746231344276, 10289.382637041801, 10290.176785857113, 10306.33008854422, 392796.0, 395832.0, 404286.0, 404848.0, 420294.0, 63915.680421598525, 86110.56944715236, 87422.56288718643, 89032.0, 92552.0, 955.9474228917476, 961.3847137831793, 979.0796651147898, 982.7150126463389, 983.7638966647953, 14913.17977511204, 15992.920035400213, 16577.25402356921, 16619.66760664804, 16946.0, 32601.510977334885, 37118.8144059278, 41514.0, 42416.36375454433, 42911.570884290755, 51960.48039519556, 52779.0, 57168.71415642895, 57474.712626438275, 58844.4115558839, 7943.880841787262, 8030.919690803017, 8175.305099066646, 8283.83432331362, 8500.70247541333, 12037.578684745991, 12456.439460224494, 13026.54407095747, 13051.477940882258, 13090.541831035867, 10684.946575267073, 11174.944125279322, 11294.152938529478, 11828.0, 11846.519185101863, 11169.0, 11295.0, 11375.31748095117, 11422.143339249757, 11794.351310677652, 6278.372162783685, 7751.689932402641, 11070.612528561462, 11487.31076135401, 11546.653600392001, 7868.803279918048, 8035.718749843727, 8040.638171282189, 8292.12932642088, 8342.290905272877, 8560.657573696983, 10569.097562438836, 11122.110231181325, 11388.544458221579, 11813.409329533317], "value": 8684.390732734802, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.6546346145192, 1430.957071287861, 1566.7493201087827, 1699.2438364927611, 1721.750346199802, 8042.356611471083, 8564.400491965556, 8567.443116197443, 8568.0, 8568.785780355494, 7037.8592428151405, 7220.458465615074, 8033.598320084002, 8281.710140145082, 8351.707690230844, 2194.73663160421, 2235.675827005083, 2235.888205589722, 2235.910563577455, 2238.183063181943, 2247.9775202248015, 2261.7851304126084, 2328.2898715891647, 2332.8483648562874, 2338.3218866528687, 2209.1826024371007, 2232.1071571371417, 2232.598132336179, 2247.6179049561533, 2253.0311965854676, 2173.967390489144, 2190.44143743346, 2192.9232476863235, 2195.83531235158, 2206.3822129803602, 2193.0, 2203.834712396577, 2212.623853944833, 2215.080741492279, 2227.175944900381, 8506.404551681384, 8506.957465212696, 8533.402661813676, 8569.528675922822, 8602.526861022641, 4985.401751789808, 5123.743812809382, 5182.766775495112, 5192.299039629639, 5195.272661827347, 3033.2416895776055, 3053.3740583180393, 3207.294395233045, 3227.951580726291, 3230.48312270038, 1008.3848852200152, 1019.1655020962238, 1032.6282538286212, 1042.890496497872, 1047.6542740895486, 10832.350058996564, 11620.767584648343, 11629.24409913353, 11639.592614258545, 11661.242019268806, 4296.377025331372, 4451.59935605797, 4463.575960283768, 4484.506704262529, 4498.955010449919, 9299.674511392204, 9306.02286759894, 9307.860382094274, 9309.627614895464, 9315.673951411669, 5164.302819119446, 5164.586833053347, 5167.0, 5167.405748338914, 5169.405518365436, 4655.5577220164705, 4777.95222047782, 4809.855704328876, 4813.9037219255415, 4861.538153875376, 3545.0, 3690.261947610435, 3702.33357995558, 3728.254349130185, 3786.5645450773513, 1118.4631376939114, 1119.9328040317607, 1131.1798945764256, 1134.6085600467825, 1136.198979719296, 918.2608000559613, 932.2215949681956, 943.0, 950.1496160936061, 987.0376383026492, 5392.946070539245, 5579.581531385163, 5679.062954612526, 5707.1153971134545, 5879.3826648202175, 421.3300851645898, 421.95569465206324, 426.3689739186022, 428.46822609172335, 436.3084511049958, 3970.722049456568, 4028.3957406389277, 4124.216398884202, 4206.684498662551, 4305.160493703697, 10398.116160126323, 10577.62978295756, 10581.36511809294, 10598.0, 10617.893821061845, 9674.22606191503, 10500.632477863239, 10542.789144217073, 10589.841152382567, 10631.787364252672, 7505.099388073357, 7860.646270917822, 7871.842563148705, 7879.251471110236, 7883.645235964395, 5567.344900761453, 5616.297962754656, 5689.601727879009, 5819.592628516132, 5965.194698715618, 9604.711858644252, 9651.662191823252, 9659.0, 9666.709998700051, 9670.323077384515, 8241.711540096068, 8526.190011948855, 9671.371360861522, 9685.6610018649, 9862.55618497155, 7312.13540547157, 7527.698892044257, 7543.585102819397, 7635.129393298677, 7729.381649467917, 8161.0, 8237.929069220963, 8302.128276530877, 8309.709160179365, 8330.250277474814, 7053.435725142079, 7080.398166155935, 7090.07828982229, 7167.749128780468, 7430.256974302527, 7044.401225895856, 7091.716331346895, 7151.8569628608175, 7203.963980180275, 7219.530730502501, 6473.902891456538, 6651.866962660816, 6731.720973015112, 6788.830279243059, 6810.62541560219, 4735.715857048588, 4740.78666460017, 4754.809807607657, 4868.780904859219, 4884.877878053077, 4918.655694101379, 4945.406551213878, 4968.900621987612, 5050.419201791694, 5167.638265321391], "value": 4333.066755719989, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.15215612715264, 376.753226636553, 378.998105009475, 380.6897378636413, 387.40146473698115, 5166.0737839242165, 5192.0, 5200.141976573861, 5208.609354298428, 5240.816571419996, 4983.401991760985, 5054.115529782292, 5147.459516750734, 5150.716710580926, 5157.922631160535, 4037.7684806134043, 4075.6943229257777, 4081.8366765471847, 4135.793210339486, 4155.501339839224, 4104.8152833122585, 4105.856295029675, 4113.197926404352, 4122.381642753581, 4145.585441455861, 4086.734362266458, 4091.4067460218253, 4091.4476545666403, 4103.240900433414, 4131.65721140629, 3946.585608511108, 4066.755994640331, 4091.2792992659993, 4095.795210239506, 4099.196353604404, 3684.1158122050783, 3690.5940346561856, 3692.8522859085606, 3696.149885526344, 3723.832427540767, 5008.098542262392, 5027.622928280357, 5039.546440820343, 5054.3176671149295, 5054.433125730999, 4601.861944141681, 4639.334002245157, 4661.813527458898, 4669.463011753658, 4687.22660760974, 5330.013947419701, 5342.439043900412, 5344.7862085516535, 5356.143017117242, 5362.6782393056155, 4083.5285362527984, 4092.1176258920636, 4099.631033206996, 4109.773962432065, 4128.793560322002, 5347.946520534822, 5579.707972296405, 5582.748776305036, 5593.580481463866, 5615.887682246333, 3963.8612648557737, 3990.521137463523, 4004.6596039336982, 4015.77913214776, 4038.324095500393, 4043.73715708478, 4044.919101617951, 4071.328230841878, 4077.2864748668862, 4077.2864748668862, 4483.820647174077, 4620.821690468909, 4648.48866624671, 4667.463241727177, 4669.112868554964, 5366.0, 5409.161579955114, 5439.48324909133, 5440.836774896759, 5460.563154947595, 1342.8724271194221, 1350.581319790868, 1352.6415499892537, 1360.340234986031, 1361.2241022617013, 884.3720958119807, 939.8402271613842, 945.3004776465366, 948.0235357581702, 950.8098380323825, 5110.616703747234, 5157.871053223699, 5176.922346164811, 5194.584433245332, 5209.192575150858, 5366.651167674088, 5374.408815030343, 5413.83758487246, 5483.506484416422, 5488.063885821067, 5043.268726034705, 5183.688978661293, 5214.7914083436235, 5227.895442091137, 5240.554552862973, 2894.2040938742016, 2898.884044638233, 2903.5499497577907, 2904.3320036391747, 2905.5786910898223, 5541.33503979525, 5546.4453554644215, 5649.943500565024, 5693.544516438675, 5714.285714285715, 5296.285001524745, 5494.670319780826, 5515.283013208257, 5534.612577119563, 5534.987134327661, 5672.978863804472, 5698.772049118154, 5709.400512946081, 5720.513756330759, 5747.798827041033, 5519.31008623922, 5543.556515478833, 5571.415001424954, 5575.68246893098, 5580.302462192226, 5185.692366896695, 5263.315679024621, 5436.53789427903, 5451.890962180655, 5553.333599968029, 5726.055200891807, 5736.885262294814, 5757.453041961007, 5787.421257874179, 5806.5645076618575, 4004.3593025115842, 4011.615992482589, 4020.597940206073, 4050.311447054067, 4055.3916912463364, 5382.19267109921, 5580.637258578179, 5601.831945041656, 5614.85962850932, 5623.971880140573, 5473.0, 5478.287822583039, 5489.85895500763, 5491.835244942658, 5512.456512176597, 5339.73301334924, 5424.5660347172925, 5489.85895500747, 5496.917546236729, 5523.453433038756, 5371.355437347544, 5380.569554435558, 5394.599403265509, 5398.784048638167, 5399.865003374947, 5470.398256191896, 5480.205370221375, 5488.204210389551, 5522.763280871253, 5527.419620939743, 5250.737463126752, 5326.866828329323, 5329.307190065267, 5373.677579345252, 5406.78372865081], "value": 4168.549584006815, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.2056896276172, 718.4432065149508, 745.608555508358, 795.0856515007744, 796.6295672512281, 15585.844141558595, 16681.0, 16733.079680617546, 16736.497905062854, 16746.66506669869, 13739.931300343509, 13790.793138102912, 13817.447302107928, 13834.169949802994, 13836.654083647914, 663.5355251324069, 677.1975209376905, 769.9422543309246, 779.185750890321, 795.8686816675241, 772.420684486635, 786.3512602103259, 787.4527203593505, 787.6967367563501, 787.9566623835686, 847.0513025411548, 847.8092429203441, 848.2069265236993, 848.3806821020659, 848.4103548034119, 705.326413275326, 720.2977097330114, 805.2350267246081, 840.3865178419787, 841.8021764885277, 603.3634515586054, 607.5534482155612, 607.9787207447719, 607.9787207447719, 608.0787606775739, 15136.318865650963, 15497.07017578949, 15503.147326897017, 15557.92221038899, 15575.610609734735, 12513.812292815615, 12876.420561074774, 13209.0, 13579.728405431935, 14197.290135493287, 12733.299668518228, 12937.4178161982, 12996.220226786423, 13052.0, 13121.0, 4318.935215971763, 4337.956620433787, 4392.736435813861, 4435.866923992286, 4449.644028477714, 76298.0, 79638.0, 82436.58781706112, 82962.58518707428, 85564.14435855624, 10846.294990825729, 10932.508037138348, 10954.945225273823, 11143.164262680331, 11167.0, 20223.08996095179, 20335.084921178583, 20360.89819550893, 20469.89765051165, 20591.691124633144, 7623.809404764815, 7697.4226932980255, 7740.380769538423, 7780.766577002586, 7886.921130788619, 4254.893627659271, 4458.888527786831, 4555.134524440412, 4561.840335588305, 4621.353010578522, 3474.0, 3586.4799604057266, 3593.8203089845924, 3597.8021208833243, 3610.7291953103622, 609.4149616368247, 609.978286370328, 615.6829232945037, 616.9290531588924, 631.2172905597026, 658.9077529145924, 659.9968048566152, 705.8164877131983, 711.9786406407816, 712.8004158835535, 8837.333650190129, 8875.28997680184, 9185.540722963826, 9216.430106674849, 9793.265505087147, 593.1902952469934, 618.7865186510646, 674.1674032569772, 677.9423748981392, 680.7991642465485, 4086.4891888513935, 4272.401863739079, 4340.76125813077, 4423.977880110579, 4441.378207051015, 81084.59457702674, 84160.57919710362, 84259.0, 86290.81362733472, 86546.56726716452, 73594.26405735982, 73839.63080184565, 73999.63000185072, 74256.0, 74490.62754686191, 63322.68338658277, 63618.68190659017, 64112.35887641157, 64773.352266478614, 65549.0, 8601.655933762579, 8679.218870301704, 8691.130886911258, 8734.737957861274, 8774.225774225773, 45087.774561126986, 45955.77022114868, 46283.0, 46691.29963050489, 47233.527664724286, 56226.43773562212, 61026.38973610207, 61034.389656102874, 61924.0, 62983.37016629775, 11087.0, 11508.424578771195, 11546.76906461883, 11615.303081815118, 12288.0, 11366.43167841588, 11918.225315354473, 13399.196048237138, 13421.731565368442, 13859.86140138626, 12221.633350999484, 12596.55912043074, 12715.754636575137, 13035.47858085666, 13182.60452186436, 10963.451827408757, 10978.560857565928, 10980.505877235384, 11133.80288705796, 11161.888381116085, 11577.421128943688, 11874.76250475003, 11943.343116128348, 12157.635270941886, 12279.14046016806, 10306.227032972407, 10312.535935883054, 10404.73988150272, 10441.530131144269, 10536.315139515908, 9825.213982881209, 10632.84050739255, 10663.946680266548, 10703.946480267548, 11179.441027948733], "value": 7034.276178774316, "unit": "score"}], "name": "dromaeo_css", "value": 6905.528797857768, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 114.0, 114.0, 113.0, 114.0, 111.0, 111.0, 111.0, 113.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 121.0, 121.0, 157.0, 124.0, 127.0, 126.0, 124.0, 145.0, 120.0], "value": 131.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 161.0, 162.0, 162.0, 162.0, 163.0, 161.0, 162.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 113.0, 78.0, 75.0, 75.0, 76.0, 91.0, 76.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 109.0, 83.0, 84.0, 84.0, 95.0, 85.0, 83.0, 109.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 145.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 53.0, 54.0, 54.0, 55.0, 55.0, 55.0, 55.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 63.0, 63.0, 64.0, 63.0, 63.0, 62.0, 62.0, 64.0], "value": 63.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 79.0, 86.0, 79.0, 78.0, 89.0, 79.0, 78.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 131.0, 137.0, 126.0, 127.0, 130.0, 133.0, 125.0, 163.0, 140.0], "value": 134.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 168.0, 165.0, 161.0, 170.0, 168.0, 164.0, 165.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 67.0, 63.0, 68.0, 63.0, 72.0, 62.0, 62.0, 61.0, 62.0], "value": 64.2, "unit": "score"}], "name": "kraken", "value": 1498.1999999999998, "alertThreshold": 2.0}]} 09:32:13 INFO - Return code: 0 09:32:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:32:13 INFO - # TBPL SUCCESS # 09:32:13 INFO - Running post-action listener: _resource_record_post_action 09:32:13 INFO - [mozharness: 2016-12-21 17:32:13.238725Z] Finished run-tests step (success) 09:32:13 INFO - Running post-run listener: _resource_record_post_run 09:32:13 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 0; Write bytes: 398925824; Read time: 0; Write time: 250276 09:32:13 INFO - TinderboxPrint: CPU usage
13.1% 09:32:13 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:32:13 INFO - TinderboxPrint: I/O write bytes / time
398,925,824 / 250,276 09:32:13 INFO - TinderboxPrint: CPU idle
8,284.6 (86.8%) 09:32:13 INFO - TinderboxPrint: CPU user
1,208.5 (12.7%) 09:32:13 INFO - TinderboxPrint: Swap in / out
0 / 0 09:32:13 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107778048; Read time: 0; Write time: 78092 09:32:13 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 290533376; Read time: 0; Write time: 172160 09:32:13 INFO - Running post-run listener: _upload_blobber_files 09:32:13 INFO - Blob upload gear active. 09:32:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:32:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:32:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:32:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:32:14 INFO - (blobuploader) - INFO - Open directory for files ... 09:32:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:32:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:32:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:15 INFO - (blobuploader) - INFO - Done attempting. 09:32:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:32:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:32:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:18 INFO - (blobuploader) - INFO - Done attempting. 09:32:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:32:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:32:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:19 INFO - (blobuploader) - INFO - Done attempting. 09:32:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:32:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:32:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:20 INFO - (blobuploader) - INFO - Done attempting. 09:32:20 INFO - (blobuploader) - INFO - Iteration through files over. 09:32:20 INFO - Return code: 0 09:32:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:32:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:32:20 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e88bf6df50609e5e8f64f59d2b2a5e3e98312b381b6448ad1961a3c4273fd6645a144d6fa0144e0232769661828b60e4d70511db2d86b079fcd98b8927d0e277", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ec4b34f21153a5888665aa30c75f436789a23b03b29274593416e017ee24284ec780290f56641626be092f60c7f639f3266752942478802330eaa3a12eb4bc1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41deac894cf2aec583ccdc247694194f1e54c692edb9cd485f8ce3cd9a7d52441ff56aca937dbee53f085353570f8b7193d929abf731d678ff8ce9409468431e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39a6db1d5e7775fb696aca0f79a04c0ef3f8efa38dc4da7b7852f8dfef66d08f93e03e13e1847394359be2c991d2835f58c0f831b7e95bf8b4bd50ad46efa658"} 09:32:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:32:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:32:20 INFO - Contents: 09:32:20 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e88bf6df50609e5e8f64f59d2b2a5e3e98312b381b6448ad1961a3c4273fd6645a144d6fa0144e0232769661828b60e4d70511db2d86b079fcd98b8927d0e277", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ec4b34f21153a5888665aa30c75f436789a23b03b29274593416e017ee24284ec780290f56641626be092f60c7f639f3266752942478802330eaa3a12eb4bc1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41deac894cf2aec583ccdc247694194f1e54c692edb9cd485f8ce3cd9a7d52441ff56aca937dbee53f085353570f8b7193d929abf731d678ff8ce9409468431e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39a6db1d5e7775fb696aca0f79a04c0ef3f8efa38dc4da7b7852f8dfef66d08f93e03e13e1847394359be2c991d2835f58c0f831b7e95bf8b4bd50ad46efa658"} 09:32:20 INFO - Running post-run listener: copy_logs_to_upload_dir 09:32:20 INFO - Copying logs to upload dir... 09:32:20 INFO - mkdir: /builds/slave/test/build/upload/logs 09:32:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.850883 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-12-21 09:32:19.209123) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 09:32:19.212998) ========= 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-1482340294.809857-331589976 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e88bf6df50609e5e8f64f59d2b2a5e3e98312b381b6448ad1961a3c4273fd6645a144d6fa0144e0232769661828b60e4d70511db2d86b079fcd98b8927d0e277", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ec4b34f21153a5888665aa30c75f436789a23b03b29274593416e017ee24284ec780290f56641626be092f60c7f639f3266752942478802330eaa3a12eb4bc1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41deac894cf2aec583ccdc247694194f1e54c692edb9cd485f8ce3cd9a7d52441ff56aca937dbee53f085353570f8b7193d929abf731d678ff8ce9409468431e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39a6db1d5e7775fb696aca0f79a04c0ef3f8efa38dc4da7b7852f8dfef66d08f93e03e13e1847394359be2c991d2835f58c0f831b7e95bf8b4bd50ad46efa658"} build_url:https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.012045 build_url: 'https://queue.taskcluster.net/v1/task/DjdO3-ikTJi50AGXeUnzlQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e88bf6df50609e5e8f64f59d2b2a5e3e98312b381b6448ad1961a3c4273fd6645a144d6fa0144e0232769661828b60e4d70511db2d86b079fcd98b8927d0e277", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ec4b34f21153a5888665aa30c75f436789a23b03b29274593416e017ee24284ec780290f56641626be092f60c7f639f3266752942478802330eaa3a12eb4bc1", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41deac894cf2aec583ccdc247694194f1e54c692edb9cd485f8ce3cd9a7d52441ff56aca937dbee53f085353570f8b7193d929abf731d678ff8ce9409468431e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39a6db1d5e7775fb696aca0f79a04c0ef3f8efa38dc4da7b7852f8dfef66d08f93e03e13e1847394359be2c991d2835f58c0f831b7e95bf8b4bd50ad46efa658"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 09:32:19.241984) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:32:19.242568) ========= 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-1482340294.809857-331589976 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004406 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:32:19.323697) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 09:32:19.324107) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 09:32:19.324456) ========= ========= Total master_lag: 0.18 =========