builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-022 starttime: 1476528296.64 results: success (0) buildid: 20161015004013 builduid: b5c404cf19444d138b1e84068cb80486 revision: 7bc185ff4e8b66536bf314f9cf8b03f7d7f0b9b8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-15 03:44:56.637340) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-15 03:44:56.637821) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-15 03:44:56.638105) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-15 03:44:56.652570) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 03:44:56.652913) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-15 03:44:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2016-10-15 03:44:57 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135343 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 03:44:56.802061) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 03:44:56.802377) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018792 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 03:44:56.851668) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 03:44:56.852039) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7bc185ff4e8b66536bf314f9cf8b03f7d7f0b9b8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7bc185ff4e8b66536bf314f9cf8b03f7d7f0b9b8 --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=9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-15 03:44:57,441 truncating revision to first 12 chars 2016-10-15 03:44:57,441 Setting DEBUG logging. 2016-10-15 03:44:57,441 attempt 1/10 2016-10-15 03:44:57,441 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7bc185ff4e8b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-15 03:44:57,864 unpacking tar archive at: mozilla-aurora-7bc185ff4e8b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.782172 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 03:44:57.647644) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-15 03:44:57.647988) ========= script_repo_revision: 7bc185ff4e8b66536bf314f9cf8b03f7d7f0b9b8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-15 03:44:57.648433) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-15 03:44:57.648743) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-15 03:44:57.659416) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-10-15 03:44:57.659810) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:44:58 INFO - MultiFileLogger online at 20161015 03:44:58 in /builds/slave/test 03:44:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:44:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:44:58 INFO - {'append_to_log': False, 03:44:58 INFO - 'base_work_dir': '/builds/slave/test', 03:44:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:44:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:44:58 INFO - 'branch': 'Mozilla-Aurora', 03:44:58 INFO - 'buildbot_json_path': 'buildprops.json', 03:44:58 INFO - 'config_files': ('talos/linux_config.py',), 03:44:58 INFO - 'default_actions': ('clobber', 03:44:58 INFO - 'read-buildbot-config', 03:44:58 INFO - 'download-and-extract', 03:44:58 INFO - 'populate-webroot', 03:44:58 INFO - 'create-virtualenv', 03:44:58 INFO - 'install', 03:44:58 INFO - 'run-tests'), 03:44:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:44:58 INFO - 'download_minidump_stackwalk': True, 03:44:58 INFO - 'download_symbols': 'ondemand', 03:44:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:44:58 INFO - 'tooltool.py': '/tools/tooltool.py', 03:44:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:44:58 INFO - '/tools/misc-python/virtualenv.py')}, 03:44:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:44:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:44:58 INFO - 'installer_path': 'installer.exe', 03:44:58 INFO - 'log_level': 'info', 03:44:58 INFO - 'log_name': 'talos', 03:44:58 INFO - 'log_to_console': True, 03:44:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:44:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:44:58 INFO - 'opt_config_files': (), 03:44:58 INFO - 'pip_index': False, 03:44:58 INFO - 'sps_profile': False, 03:44:58 INFO - 'sps_profile_interval': 0, 03:44:58 INFO - 'suite': 'dromaeojs', 03:44:58 INFO - 'system_bits': '32', 03:44:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:44:58 INFO - 'title': 'talos-linux64-ix-022', 03:44:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:44:58 INFO - 'use_talos_json': True, 03:44:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:44:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:44:58 INFO - 'work_dir': 'build'} 03:44:58 INFO - [mozharness: 2016-10-15 10:44:58.343358Z] Running clobber step. 03:44:58 INFO - Running pre-action listener: _resource_record_pre_action 03:44:58 INFO - Running main action method: clobber 03:44:58 INFO - rmtree: /builds/slave/test/build 03:44:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:44:58 INFO - Running post-action listener: _resource_record_post_action 03:44:58 INFO - [mozharness: 2016-10-15 10:44:58.840137Z] Finished clobber step (success) 03:44:58 INFO - [mozharness: 2016-10-15 10:44:58.840237Z] Running read-buildbot-config step. 03:44:58 INFO - Running pre-action listener: _resource_record_pre_action 03:44:58 INFO - Running main action method: read_buildbot_config 03:44:58 INFO - Using buildbot properties: 03:44:58 INFO - { 03:44:58 INFO - "project": "", 03:44:58 INFO - "product": "firefox", 03:44:58 INFO - "script_repo_revision": "production", 03:44:58 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:44:58 INFO - "repository": "", 03:44:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:44:58 INFO - "buildid": "20161015004013", 03:44:58 INFO - "stage_platform": "linux64-pgo", 03:44:58 INFO - "basedir": "/builds/slave/test", 03:44:58 INFO - "nightly_build": "True", 03:44:58 INFO - "buildnumber": 577, 03:44:58 INFO - "slavename": "talos-linux64-ix-022", 03:44:58 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:44:58 INFO - "platform": "ubuntu64_hw", 03:44:58 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:44:58 INFO - "branch": "mozilla-aurora", 03:44:58 INFO - "repo_path": "releases/mozilla-aurora", 03:44:58 INFO - "revision": "7bc185ff4e8b66536bf314f9cf8b03f7d7f0b9b8", 03:44:58 INFO - "pgo_build": "True", 03:44:58 INFO - "builduid": "b5c404cf19444d138b1e84068cb80486", 03:44:58 INFO - "slavebuilddir": "test" 03:44:58 INFO - } 03:44:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 03:44:58 INFO - Running post-action listener: _resource_record_post_action 03:44:58 INFO - [mozharness: 2016-10-15 10:44:58.842764Z] Finished read-buildbot-config step (success) 03:44:58 INFO - [mozharness: 2016-10-15 10:44:58.842853Z] Running download-and-extract step. 03:44:58 INFO - Running pre-action listener: _resource_record_pre_action 03:44:58 INFO - Running main action method: download_and_extract 03:44:58 INFO - mkdir: /builds/slave/test/build/tests 03:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:58 INFO - trying https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:44:58 INFO - Downloading https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:44:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:44:59 INFO - Downloaded 1361 bytes. 03:44:59 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:44:59 INFO - Using the following test package requirements: 03:44:59 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:44:59 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:44:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:44:59 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:44:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:44:59 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:44:59 INFO - u'jsshell-linux-x86_64.zip'], 03:44:59 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:44:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:44:59 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:44:59 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:44:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:44:59 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:44:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:44:59 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:44:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:44:59 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:44:59 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:44:59 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:44:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:44:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:44:59 INFO - Fetch https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:44:59 INFO - Expected file size: 21907489 03:45:04 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:45:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:45:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:45:04 INFO - Fetch https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:45:04 INFO - Expected file size: 21907489 03:45:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 03:45:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:45:09 INFO - Fetch https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 03:45:10 INFO - Expected file size: 14414081 03:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:10 INFO - trying https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:45:10 INFO - Downloading https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:45:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:45:11 INFO - Downloaded 63993882 bytes. 03:45:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:45:11 INFO - mkdir: /builds/slave/test/properties 03:45:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:45:11 INFO - Writing to file /builds/slave/test/properties/build_url 03:45:11 INFO - Contents: 03:45:11 INFO - build_url:https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:45:12 INFO - Running post-action listener: _resource_record_post_action 03:45:12 INFO - Running post-action listener: set_extra_try_arguments 03:45:12 INFO - [mozharness: 2016-10-15 10:45:12.425532Z] Finished download-and-extract step (success) 03:45:12 INFO - [mozharness: 2016-10-15 10:45:12.425760Z] Running populate-webroot step. 03:45:12 INFO - Running pre-action listener: _resource_record_pre_action 03:45:12 INFO - Running main action method: populate_webroot 03:45:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:45:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:45:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:45:12 INFO - '--tppagecycles', 03:45:12 INFO - '7'], 03:45:12 INFO - 'tests': ['tsvgm']}}, 03:45:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:45:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:45:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:45:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:45:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:45:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:45:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:12 INFO - 'talos_options': ['--disable-e10s'], 03:45:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:45:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:45:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:12 INFO - 'talos_options': ['--disable-e10s'], 03:45:12 INFO - 'tests': ['damp', 'tps']}, 03:45:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:12 INFO - 'tests': ['damp', 'tps']}, 03:45:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:45:12 INFO - 'tests': ['dromaeo_dom']}, 03:45:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:45:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:45:12 INFO - 'tests': ['basic_compositor_video']}, 03:45:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:45:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:45:12 INFO - 'tests': ['a11yr', 03:45:12 INFO - 'ts_paint', 03:45:12 INFO - 'tpaint', 03:45:12 INFO - 'sessionrestore', 03:45:12 INFO - 'sessionrestore_no_auto_restore', 03:45:12 INFO - 'tabpaint']}, 03:45:12 INFO - 'other-e10s': {'tests': ['a11yr', 03:45:12 INFO - 'ts_paint', 03:45:12 INFO - 'tpaint', 03:45:12 INFO - 'sessionrestore', 03:45:12 INFO - 'sessionrestore_no_auto_restore', 03:45:12 INFO - 'tabpaint']}, 03:45:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:45:12 INFO - 'ts_paint', 03:45:12 INFO - 'tpaint', 03:45:12 INFO - 'sessionrestore', 03:45:12 INFO - 'sessionrestore_no_auto_restore', 03:45:12 INFO - 'tabpaint']}, 03:45:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:45:12 INFO - 'ts_paint', 03:45:12 INFO - 'tpaint', 03:45:12 INFO - 'sessionrestore', 03:45:12 INFO - 'sessionrestore_no_auto_restore', 03:45:12 INFO - 'tabpaint']}, 03:45:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:45:12 INFO - 'tests': ['a11yr', 03:45:12 INFO - 'ts_paint', 03:45:12 INFO - 'tpaint', 03:45:12 INFO - 'sessionrestore', 03:45:12 INFO - 'sessionrestore_no_auto_restore', 03:45:12 INFO - 'tabpaint']}, 03:45:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:45:12 INFO - 'tests': ['a11yr', 03:45:12 INFO - 'ts_paint', 03:45:12 INFO - 'tpaint', 03:45:12 INFO - 'sessionrestore', 03:45:12 INFO - 'sessionrestore_no_auto_restore', 03:45:12 INFO - 'tabpaint']}, 03:45:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:45:12 INFO - 'tests': ['tsvgx', 03:45:12 INFO - 'tsvgr_opacity', 03:45:12 INFO - 'tart', 03:45:12 INFO - 'tscrollx', 03:45:12 INFO - 'cart']}, 03:45:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:45:12 INFO - 'tsvgr_opacity', 03:45:12 INFO - 'tart', 03:45:12 INFO - 'tscrollx', 03:45:12 INFO - 'cart']}, 03:45:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:12 INFO - 'talos_options': ['--disable-e10s'], 03:45:12 INFO - 'tests': ['tp5o']}, 03:45:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:12 INFO - 'tests': ['tp5o']}, 03:45:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:12 INFO - 'talos_options': ['--disable-e10s', 03:45:12 INFO - '--xperf_path', 03:45:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:45:12 INFO - 'tests': ['tp5n']}, 03:45:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:12 INFO - 'talos_options': ['--xperf_path', 03:45:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:45:12 INFO - 'tests': ['tp5n']}}, 03:45:12 INFO - 'talos.zip': {'path': '', 03:45:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:45:12 INFO - Running post-action listener: _resource_record_post_action 03:45:12 INFO - [mozharness: 2016-10-15 10:45:12.441421Z] Finished populate-webroot step (success) 03:45:12 INFO - [mozharness: 2016-10-15 10:45:12.441509Z] Running create-virtualenv step. 03:45:12 INFO - Running pre-action listener: _resource_record_pre_action 03:45:12 INFO - Running main action method: create_virtualenv 03:45:12 INFO - Creating virtualenv /builds/slave/test/build/venv 03:45:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:45:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:45:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:45:12 INFO - Using real prefix '/usr' 03:45:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:45:13 INFO - Installing distribute.............................................................................................................................................................................................done. 03:45:15 INFO - Installing pip.................done. 03:45:15 INFO - Return code: 0 03:45:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f65b0b24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2178f60>, '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 0x2179d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217a1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:45:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:45:15 INFO - Using env: {'DISPLAY': ':0', 03:45:15 INFO - 'HOME': '/home/cltbld', 03:45:15 INFO - 'LANG': 'en_US.UTF-8', 03:45:15 INFO - 'LANGUAGE': 'en_US:en', 03:45:15 INFO - 'LOGNAME': 'cltbld', 03:45:15 INFO - 'MAIL': '/var/mail/cltbld', 03:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:15 INFO - 'MOZ_NO_REMOTE': '1', 03:45:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:15 INFO - 'NO_EM_RESTART': '1', 03:45:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:15 INFO - 'PWD': '/builds/slave/test', 03:45:15 INFO - 'SHELL': '/bin/bash', 03:45:15 INFO - 'SHLVL': '1', 03:45:15 INFO - 'TERM': 'linux', 03:45:15 INFO - 'TMOUT': '86400', 03:45:15 INFO - 'USER': 'cltbld', 03:45:15 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 03:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:15 INFO - '_': '/tools/buildbot/bin/python'} 03:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:15 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:45:15 INFO - Cleaning up... 03:45:15 INFO - Return code: 0 03:45:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f65b0b24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2178f60>, '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 0x2179d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217a1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:45:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:45:15 INFO - Using env: {'DISPLAY': ':0', 03:45:15 INFO - 'HOME': '/home/cltbld', 03:45:15 INFO - 'LANG': 'en_US.UTF-8', 03:45:15 INFO - 'LANGUAGE': 'en_US:en', 03:45:15 INFO - 'LOGNAME': 'cltbld', 03:45:15 INFO - 'MAIL': '/var/mail/cltbld', 03:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:15 INFO - 'MOZ_NO_REMOTE': '1', 03:45:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:15 INFO - 'NO_EM_RESTART': '1', 03:45:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:15 INFO - 'PWD': '/builds/slave/test', 03:45:15 INFO - 'SHELL': '/bin/bash', 03:45:15 INFO - 'SHLVL': '1', 03:45:15 INFO - 'TERM': 'linux', 03:45:15 INFO - 'TMOUT': '86400', 03:45:15 INFO - 'USER': 'cltbld', 03:45:15 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 03:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:15 INFO - '_': '/tools/buildbot/bin/python'} 03:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:15 INFO - Downloading/unpacking psutil>=3.1.1 03:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:45:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:45:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:45:17 INFO - Installing collected packages: psutil 03:45:17 INFO - Running setup.py install for psutil 03:45:17 INFO - building 'psutil._psutil_linux' extension 03:45:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:45:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:45:17 INFO - building 'psutil._psutil_posix' extension 03:45:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:45:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:45:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:45:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:45:17 INFO - Successfully installed psutil 03:45:17 INFO - Cleaning up... 03:45:18 INFO - Return code: 0 03:45:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f65b0b24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2178f60>, '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 0x2179d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217a1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:45:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:45:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:45:18 INFO - Using env: {'DISPLAY': ':0', 03:45:18 INFO - 'HOME': '/home/cltbld', 03:45:18 INFO - 'LANG': 'en_US.UTF-8', 03:45:18 INFO - 'LANGUAGE': 'en_US:en', 03:45:18 INFO - 'LOGNAME': 'cltbld', 03:45:18 INFO - 'MAIL': '/var/mail/cltbld', 03:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:18 INFO - 'MOZ_NO_REMOTE': '1', 03:45:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:18 INFO - 'NO_EM_RESTART': '1', 03:45:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:18 INFO - 'PWD': '/builds/slave/test', 03:45:18 INFO - 'SHELL': '/bin/bash', 03:45:18 INFO - 'SHLVL': '1', 03:45:18 INFO - 'TERM': 'linux', 03:45:18 INFO - 'TMOUT': '86400', 03:45:18 INFO - 'USER': 'cltbld', 03:45:18 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 03:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:18 INFO - '_': '/tools/buildbot/bin/python'} 03:45:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:45:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:45:20 INFO - Installing collected packages: mozsystemmonitor 03:45:20 INFO - Running setup.py install for mozsystemmonitor 03:45:20 INFO - Successfully installed mozsystemmonitor 03:45:20 INFO - Cleaning up... 03:45:20 INFO - Return code: 0 03:45:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f65b0b24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2178f60>, '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 0x2179d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217a1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:45:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:45:20 INFO - Using env: {'DISPLAY': ':0', 03:45:20 INFO - 'HOME': '/home/cltbld', 03:45:20 INFO - 'LANG': 'en_US.UTF-8', 03:45:20 INFO - 'LANGUAGE': 'en_US:en', 03:45:20 INFO - 'LOGNAME': 'cltbld', 03:45:20 INFO - 'MAIL': '/var/mail/cltbld', 03:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:20 INFO - 'MOZ_NO_REMOTE': '1', 03:45:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:20 INFO - 'NO_EM_RESTART': '1', 03:45:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:20 INFO - 'PWD': '/builds/slave/test', 03:45:20 INFO - 'SHELL': '/bin/bash', 03:45:20 INFO - 'SHLVL': '1', 03:45:20 INFO - 'TERM': 'linux', 03:45:20 INFO - 'TMOUT': '86400', 03:45:20 INFO - 'USER': 'cltbld', 03:45:20 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 03:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:20 INFO - '_': '/tools/buildbot/bin/python'} 03:45:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:20 INFO - Downloading/unpacking jsonschema==2.5.1 03:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:45:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:45:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:22 INFO - Downloading functools32-3.2.3-2.tar.gz 03:45:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:45:22 INFO - warning: no files found matching '*.txt' 03:45:22 INFO - no previously-included directories found matching 'build' 03:45:22 INFO - no previously-included directories found matching 'dist' 03:45:22 INFO - no previously-included directories found matching '.git*' 03:45:22 INFO - Installing collected packages: jsonschema, functools32 03:45:22 INFO - Running setup.py install for functools32 03:45:22 INFO - warning: no files found matching '*.txt' 03:45:22 INFO - no previously-included directories found matching 'build' 03:45:22 INFO - no previously-included directories found matching 'dist' 03:45:22 INFO - no previously-included directories found matching '.git*' 03:45:22 INFO - Successfully installed jsonschema functools32 03:45:22 INFO - Cleaning up... 03:45:23 INFO - Return code: 0 03:45:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f65b0b24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2178f60>, '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 0x2179d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217a1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:45:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:45:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:45:23 INFO - Using env: {'DISPLAY': ':0', 03:45:23 INFO - 'HOME': '/home/cltbld', 03:45:23 INFO - 'LANG': 'en_US.UTF-8', 03:45:23 INFO - 'LANGUAGE': 'en_US:en', 03:45:23 INFO - 'LOGNAME': 'cltbld', 03:45:23 INFO - 'MAIL': '/var/mail/cltbld', 03:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:23 INFO - 'MOZ_NO_REMOTE': '1', 03:45:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:23 INFO - 'NO_EM_RESTART': '1', 03:45:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:23 INFO - 'PWD': '/builds/slave/test', 03:45:23 INFO - 'SHELL': '/bin/bash', 03:45:23 INFO - 'SHLVL': '1', 03:45:23 INFO - 'TERM': 'linux', 03:45:23 INFO - 'TMOUT': '86400', 03:45:23 INFO - 'USER': 'cltbld', 03:45:23 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 03:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:23 INFO - '_': '/tools/buildbot/bin/python'} 03:45:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:45:23 INFO - Cleaning up... 03:45:23 INFO - Return code: 0 03:45:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f65b0b24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2178f60>, '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 0x2179d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217a1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:45:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:45:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:45:23 INFO - Using env: {'DISPLAY': ':0', 03:45:23 INFO - 'HOME': '/home/cltbld', 03:45:23 INFO - 'LANG': 'en_US.UTF-8', 03:45:23 INFO - 'LANGUAGE': 'en_US:en', 03:45:23 INFO - 'LOGNAME': 'cltbld', 03:45:23 INFO - 'MAIL': '/var/mail/cltbld', 03:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:23 INFO - 'MOZ_NO_REMOTE': '1', 03:45:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:23 INFO - 'NO_EM_RESTART': '1', 03:45:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:23 INFO - 'PWD': '/builds/slave/test', 03:45:23 INFO - 'SHELL': '/bin/bash', 03:45:23 INFO - 'SHLVL': '1', 03:45:23 INFO - 'TERM': 'linux', 03:45:23 INFO - 'TMOUT': '86400', 03:45:23 INFO - 'USER': 'cltbld', 03:45:23 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 03:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:23 INFO - '_': '/tools/buildbot/bin/python'} 03:45:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:23 INFO - Downloading/unpacking blobuploader==1.2.4 03:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:25 INFO - Downloading blobuploader-1.2.4.tar.gz 03:45:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:45:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:45:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:26 INFO - Downloading docopt-0.6.1.tar.gz 03:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:45:26 INFO - Installing collected packages: blobuploader, requests, docopt 03:45:26 INFO - Running setup.py install for blobuploader 03:45:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:45:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:45:26 INFO - Running setup.py install for requests 03:45:26 INFO - Running setup.py install for docopt 03:45:26 INFO - Successfully installed blobuploader requests docopt 03:45:26 INFO - Cleaning up... 03:45:26 INFO - Return code: 0 03:45:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:26 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 0x7f65b0b24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2178f60>, '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 0x2179d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217a1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:45:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:45:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:45:26 INFO - Using env: {'DISPLAY': ':0', 03:45:26 INFO - 'HOME': '/home/cltbld', 03:45:26 INFO - 'LANG': 'en_US.UTF-8', 03:45:26 INFO - 'LANGUAGE': 'en_US:en', 03:45:26 INFO - 'LOGNAME': 'cltbld', 03:45:26 INFO - 'MAIL': '/var/mail/cltbld', 03:45:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:26 INFO - 'MOZ_NO_REMOTE': '1', 03:45:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:26 INFO - 'NO_EM_RESTART': '1', 03:45:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:26 INFO - 'PWD': '/builds/slave/test', 03:45:26 INFO - 'SHELL': '/bin/bash', 03:45:26 INFO - 'SHLVL': '1', 03:45:26 INFO - 'TERM': 'linux', 03:45:26 INFO - 'TMOUT': '86400', 03:45:26 INFO - 'USER': 'cltbld', 03:45:26 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 03:45:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:26 INFO - '_': '/tools/buildbot/bin/python'} 03:45:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:45:26 INFO - Running setup.py (path:/tmp/pip-AwVYkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:45:27 INFO - Running setup.py (path:/tmp/pip-PD4WBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:45:27 INFO - Running setup.py (path:/tmp/pip-PR9pni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:45:27 INFO - Running setup.py (path:/tmp/pip-ZDzSrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:45:27 INFO - Running setup.py (path:/tmp/pip-EbbDG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:45:27 INFO - Running setup.py (path:/tmp/pip-xUXg3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:45:27 INFO - Running setup.py (path:/tmp/pip-7y2aME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:45:27 INFO - Running setup.py (path:/tmp/pip-YcI8to-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:45:27 INFO - Running setup.py (path:/tmp/pip-ZqPQPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:45:27 INFO - Running setup.py (path:/tmp/pip-T7xGbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:45:27 INFO - Running setup.py (path:/tmp/pip-TtBnlz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:45:27 INFO - Running setup.py (path:/tmp/pip-2YueXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:45:27 INFO - Running setup.py (path:/tmp/pip-tOiPEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:45:27 INFO - Running setup.py (path:/tmp/pip-7qDM1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:45:27 INFO - Running setup.py (path:/tmp/pip-6GTcYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:45:28 INFO - Running setup.py (path:/tmp/pip-xlZBeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:45:28 INFO - Running setup.py (path:/tmp/pip-GqIexp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:45:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:45:28 INFO - Running setup.py install for manifestparser 03:45:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:45:28 INFO - Running setup.py install for mozcrash 03:45:28 INFO - Running setup.py install for mozdebug 03:45:28 INFO - Running setup.py install for mozdevice 03:45:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:45:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:45:28 INFO - Running setup.py install for mozfile 03:45:28 INFO - Running setup.py install for mozhttpd 03:45:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:45:28 INFO - Running setup.py install for mozinfo 03:45:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:45:28 INFO - Running setup.py install for mozInstall 03:45:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:45:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:45:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:45:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:45:29 INFO - Running setup.py install for mozleak 03:45:29 INFO - Running setup.py install for mozlog 03:45:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:45:29 INFO - Running setup.py install for moznetwork 03:45:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:45:29 INFO - Running setup.py install for mozprocess 03:45:29 INFO - Running setup.py install for mozprofile 03:45:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:45:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:45:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:45:29 INFO - Running setup.py install for mozrunner 03:45:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:45:29 INFO - Running setup.py install for mozscreenshot 03:45:29 INFO - Running setup.py install for moztest 03:45:29 INFO - Running setup.py install for mozversion 03:45:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:45:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:45:29 INFO - Cleaning up... 03:45:30 INFO - Return code: 0 03:45:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:45:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f65b0b24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2178f60>, '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 0x2179d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217a1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:45:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:45:30 INFO - Using env: {'DISPLAY': ':0', 03:45:30 INFO - 'HOME': '/home/cltbld', 03:45:30 INFO - 'LANG': 'en_US.UTF-8', 03:45:30 INFO - 'LANGUAGE': 'en_US:en', 03:45:30 INFO - 'LOGNAME': 'cltbld', 03:45:30 INFO - 'MAIL': '/var/mail/cltbld', 03:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:30 INFO - 'MOZ_NO_REMOTE': '1', 03:45:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:30 INFO - 'NO_EM_RESTART': '1', 03:45:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:30 INFO - 'PWD': '/builds/slave/test', 03:45:30 INFO - 'SHELL': '/bin/bash', 03:45:30 INFO - 'SHLVL': '1', 03:45:30 INFO - 'TERM': 'linux', 03:45:30 INFO - 'TMOUT': '86400', 03:45:30 INFO - 'USER': 'cltbld', 03:45:30 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 03:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:30 INFO - '_': '/tools/buildbot/bin/python'} 03:45:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:45:30 INFO - Running setup.py (path:/tmp/pip-bcVyMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:45:30 INFO - Running setup.py (path:/tmp/pip-rqPBP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:45:30 INFO - Running setup.py (path:/tmp/pip-H8k0GA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:45:30 INFO - Running setup.py (path:/tmp/pip-b8Br9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:45:30 INFO - Running setup.py (path:/tmp/pip-EMR9Gq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:45:30 INFO - Running setup.py (path:/tmp/pip-ZcqOmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:45:30 INFO - Running setup.py (path:/tmp/pip-CfC0qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:45:30 INFO - Running setup.py (path:/tmp/pip-BhbC7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:45:30 INFO - Running setup.py (path:/tmp/pip-hm6aLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:45:30 INFO - Running setup.py (path:/tmp/pip-bDDKrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:45:30 INFO - Running setup.py (path:/tmp/pip-P806uM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:45:31 INFO - Running setup.py (path:/tmp/pip-eCHHYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:45:31 INFO - Running setup.py (path:/tmp/pip-sKjNHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:45:31 INFO - Running setup.py (path:/tmp/pip-Su4Gma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:45:31 INFO - Running setup.py (path:/tmp/pip-ZlsdAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:45:31 INFO - Running setup.py (path:/tmp/pip-laTCXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:45:31 INFO - Running setup.py (path:/tmp/pip-4dZD9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:45:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:33 INFO - Downloading blessings-1.6.tar.gz 03:45:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:45:33 INFO - Installing collected packages: blessings 03:45:33 INFO - Running setup.py install for blessings 03:45:33 INFO - Successfully installed blessings 03:45:33 INFO - Cleaning up... 03:45:33 INFO - Return code: 0 03:45:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:45:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:45:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:45:33 INFO - Reading from file tmpfile_stdout 03:45:33 INFO - Current package versions: 03:45:33 INFO - argparse == 1.2.1 03:45:33 INFO - blessings == 1.6 03:45:33 INFO - blobuploader == 1.2.4 03:45:33 INFO - docopt == 0.6.1 03:45:33 INFO - functools32 == 3.2.3-2 03:45:33 INFO - jsonschema == 2.5.1 03:45:33 INFO - manifestparser == 1.1 03:45:33 INFO - mozInstall == 1.12 03:45:33 INFO - mozcrash == 0.17 03:45:33 INFO - mozdebug == 0.1 03:45:33 INFO - mozdevice == 0.48 03:45:33 INFO - mozfile == 1.2 03:45:33 INFO - mozhttpd == 0.7 03:45:33 INFO - mozinfo == 0.9 03:45:33 INFO - mozleak == 0.1 03:45:33 INFO - mozlog == 3.4 03:45:33 INFO - moznetwork == 0.27 03:45:33 INFO - mozprocess == 0.23 03:45:33 INFO - mozprofile == 0.28 03:45:33 INFO - mozrunner == 6.12 03:45:33 INFO - mozscreenshot == 0.1 03:45:33 INFO - mozsystemmonitor == 0.3 03:45:33 INFO - moztest == 0.7 03:45:33 INFO - mozversion == 1.4 03:45:33 INFO - psutil == 3.1.1 03:45:33 INFO - requests == 1.2.3 03:45:33 INFO - wsgiref == 0.1.2 03:45:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:45:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:33 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 0x7f65b0b24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2178f60>, '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 0x2179d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217a1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:45:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:45:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:45:33 INFO - Using env: {'DISPLAY': ':0', 03:45:33 INFO - 'HOME': '/home/cltbld', 03:45:33 INFO - 'LANG': 'en_US.UTF-8', 03:45:33 INFO - 'LANGUAGE': 'en_US:en', 03:45:33 INFO - 'LOGNAME': 'cltbld', 03:45:33 INFO - 'MAIL': '/var/mail/cltbld', 03:45:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:33 INFO - 'MOZ_NO_REMOTE': '1', 03:45:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:33 INFO - 'NO_EM_RESTART': '1', 03:45:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:33 INFO - 'PWD': '/builds/slave/test', 03:45:33 INFO - 'SHELL': '/bin/bash', 03:45:33 INFO - 'SHLVL': '1', 03:45:33 INFO - 'TERM': 'linux', 03:45:33 INFO - 'TMOUT': '86400', 03:45:33 INFO - 'USER': 'cltbld', 03:45:33 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 03:45:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:33 INFO - '_': '/tools/buildbot/bin/python'} 03:45:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:45:34 INFO - Cleaning up... 03:45:34 INFO - Return code: 0 03:45:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:45: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')]} 03:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45: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')]} 03:45:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f65b0b24f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2178f60>, '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 0x2179d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217a1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:45:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:45:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:45:34 INFO - Using env: {'DISPLAY': ':0', 03:45:34 INFO - 'HOME': '/home/cltbld', 03:45:34 INFO - 'LANG': 'en_US.UTF-8', 03:45:34 INFO - 'LANGUAGE': 'en_US:en', 03:45:34 INFO - 'LOGNAME': 'cltbld', 03:45:34 INFO - 'MAIL': '/var/mail/cltbld', 03:45:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:34 INFO - 'MOZ_NO_REMOTE': '1', 03:45:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:34 INFO - 'NO_EM_RESTART': '1', 03:45:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:34 INFO - 'PWD': '/builds/slave/test', 03:45:34 INFO - 'SHELL': '/bin/bash', 03:45:34 INFO - 'SHLVL': '1', 03:45:34 INFO - 'TERM': 'linux', 03:45:34 INFO - 'TMOUT': '86400', 03:45:34 INFO - 'USER': 'cltbld', 03:45:34 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 03:45:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:34 INFO - '_': '/tools/buildbot/bin/python'} 03:45:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:45:34 INFO - Cleaning up... 03:45:34 INFO - Return code: 0 03:45:34 INFO - Running post-action listener: _resource_record_post_action 03:45:34 INFO - Running post-action listener: _start_resource_monitoring 03:45:34 INFO - Starting resource monitoring. 03:45:34 INFO - [mozharness: 2016-10-15 10:45:34.311920Z] Finished create-virtualenv step (success) 03:45:34 INFO - [mozharness: 2016-10-15 10:45:34.312413Z] Running install step. 03:45:34 INFO - Running pre-action listener: _resource_record_pre_action 03:45:34 INFO - Running main action method: install 03:45:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:45:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:45:34 INFO - Reading from file tmpfile_stdout 03:45:34 INFO - Detecting whether we're running mozinstall >=1.0... 03:45:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:45:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:45:34 INFO - Reading from file tmpfile_stdout 03:45:34 INFO - Output received: 03:45:34 INFO - Usage: mozinstall [options] installer 03:45:34 INFO - Options: 03:45:34 INFO - -h, --help show this help message and exit 03:45:34 INFO - -d DEST, --destination=DEST 03:45:34 INFO - Directory to install application into. [default: 03:45:34 INFO - "/builds/slave/test"] 03:45:34 INFO - --app=APP Application being installed. [default: firefox] 03:45:34 INFO - mkdir: /builds/slave/test/build/application 03:45:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:45:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:45:50 INFO - Reading from file tmpfile_stdout 03:45:50 INFO - Output received: 03:45:50 INFO - /builds/slave/test/build/application/firefox/firefox 03:45:50 INFO - Running post-action listener: _resource_record_post_action 03:45:50 INFO - [mozharness: 2016-10-15 10:45:50.581176Z] Finished install step (success) 03:45:50 INFO - [mozharness: 2016-10-15 10:45:50.581492Z] Running run-tests step. 03:45:50 INFO - Running pre-action listener: _resource_record_pre_action 03:45:50 INFO - Running main action method: run_tests 03:45:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:45:50 INFO - Python 2.7.3 03:45:50 INFO - Return code: 0 03:45:50 INFO - grabbing minidump binary from tooltool 03:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:45:50 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 0x2178f60>, '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 0x2179d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217a1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:45:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:45:50 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:45:50 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:45:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:45:50 INFO - Return code: 0 03:45:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:45:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:45:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:45:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:45:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:45:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:45:50 INFO - Using env: {'DISPLAY': ':0', 03:45:50 INFO - 'HOME': '/home/cltbld', 03:45:50 INFO - 'LANG': 'en_US.UTF-8', 03:45:50 INFO - 'LANGUAGE': 'en_US:en', 03:45:50 INFO - 'LOGNAME': 'cltbld', 03:45:50 INFO - 'MAIL': '/var/mail/cltbld', 03:45:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:45:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:50 INFO - 'MOZ_NO_REMOTE': '1', 03:45:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:45:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:50 INFO - 'NO_EM_RESTART': '1', 03:45:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:50 INFO - 'PWD': '/builds/slave/test', 03:45:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:45:50 INFO - 'SHELL': '/bin/bash', 03:45:50 INFO - 'SHLVL': '1', 03:45:50 INFO - 'TERM': 'linux', 03:45:50 INFO - 'TMOUT': '86400', 03:45:50 INFO - 'USER': 'cltbld', 03:45:50 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511', 03:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:50 INFO - '_': '/tools/buildbot/bin/python'} 03:45:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:45:50 INFO - mozversion application_buildid: 20161015004013 03:45:50 INFO - mozversion application_changeset: 7bc185ff4e8b66536bf314f9cf8b03f7d7f0b9b8 03:45:50 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:45:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:45:50 INFO - mozversion application_name: Firefox 03:45:50 INFO - mozversion application_remotingname: firefox-dev 03:45:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:45:50 INFO - mozversion application_vendor: Mozilla 03:45:50 INFO - mozversion application_version: 51.0a2 03:45:50 INFO - mozversion platform_buildid: 20161015004013 03:45:50 INFO - mozversion platform_changeset: 7bc185ff4e8b66536bf314f9cf8b03f7d7f0b9b8 03:45:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:45:50 INFO - mozversion platform_version: 51.0a2 03:45:50 INFO - using testdate: 1476528350 03:45:50 INFO - actual date: 1476528350 03:45:50 INFO - starting webserver on 'localhost:60343' 03:45:50 INFO - SUITE-START | Running 2 tests 03:45:50 INFO - TEST-START | dromaeo_css 03:45:50 INFO - operating with platform_type : linux_ 03:45:50 INFO - Initialising browser for dromaeo_css test... 03:45:50 INFO - TEST-INFO | started process 6604 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8FLKWT/profile http://localhost:60343/getInfo.html) 03:45:54 INFO - PROCESS | 6604 | __metrics Screen width/height:1600/1200 03:45:54 INFO - PROCESS | 6604 | colorDepth:24 03:45:54 INFO - PROCESS | 6604 | Browser inner width/height: 1024/697 03:45:54 INFO - PROCESS | 6604 | __metrics 03:45:55 INFO - PROCESS | 6604 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:45:55 INFO - PROCESS | 6604 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:45:56 INFO - PROCESS | 6604 | console.error: PushService: 03:45:56 INFO - PROCESS | 6604 | stateChangeProcessEnqueue: Error transitioning state 03:45:56 INFO - PROCESS | 6604 | UnknownError 03:45:56 INFO - TEST-INFO | 6604: exit 0 03:45:56 INFO - Browser initialized. 03:45:56 INFO - Running cycle 1/1 for dromaeo_css test... 03:45:56 INFO - TEST-INFO | started process 6696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8FLKWT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:46:08 INFO - PROCESS | 6696 | RSS: Main: 147849216 03:46:08 INFO - PROCESS | 6696 | 03:46:59 INFO - PROCESS | 6696 | 1476528419410 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:49:08 INFO - PROCESS | 6696 | Cycle 1(1): loaded http://localhost:60343/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60343/tests/dromaeo/cssquery-ext.html) 03:49:09 INFO - PROCESS | 6696 | RSS: Main: 235024384 03:49:09 INFO - PROCESS | 6696 | 03:52:12 INFO - PROCESS | 6696 | Cycle 1(1): loaded http://localhost:60343/tests/dromaeo/cssquery-ext.html (next: http://localhost:60343/tests/dromaeo/cssquery-jquery.html) 03:52:12 INFO - PROCESS | 6696 | RSS: Main: 235696128 03:52:12 INFO - PROCESS | 6696 | 03:55:14 INFO - PROCESS | 6696 | Cycle 1(1): loaded http://localhost:60343/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60343/tests/dromaeo/cssquery-mootools.html) 03:55:15 INFO - PROCESS | 6696 | RSS: Main: 204939264 03:55:15 INFO - PROCESS | 6696 | 03:58:17 INFO - PROCESS | 6696 | Cycle 1(1): loaded http://localhost:60343/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60343/tests/dromaeo/cssquery-prototype.html) 03:58:17 INFO - PROCESS | 6696 | RSS: Main: 223744000 03:58:17 INFO - PROCESS | 6696 | 04:01:20 INFO - PROCESS | 6696 | Cycle 1(1): loaded http://localhost:60343/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60343/tests/dromaeo/cssquery-yui.html) 04:01:21 INFO - PROCESS | 6696 | RSS: Main: 176513024 04:01:21 INFO - PROCESS | 6696 | 04:04:23 INFO - PROCESS | 6696 | Cycle 1(1): loaded http://localhost:60343/tests/dromaeo/cssquery-yui.html (next: http://localhost:60343/tests/dromaeo/cssquery-dojo.html) 04:04:23 INFO - PROCESS | 6696 | RSS: Main: 265097216 04:04:23 INFO - PROCESS | 6696 | 04:04:23 INFO - PROCESS | 6696 | __start_tp_report 04:04:23 INFO - PROCESS | 6696 | _x_x_mozilla_page_load 04:04:23 INFO - PROCESS | 6696 | _x_x_mozilla_page_load_details 04:04:23 INFO - PROCESS | 6696 | |i|pagename|runs| 04:04:23 INFO - PROCESS | 6696 | |0;dojo.html;3145.81125132492;3181.6500184979664;3191.2341038150844;3205.8397080145987;3221.6295126060495;5591.7483713232905;5636.887262254758;5667.744951477183;5672.404397538261;5676.375598684142;5533.584981126412;5579.023670857604;5581.107022876341;5586.888262234753;5605.1872478490595;5036.2949187113745;5058.59531237502;5093.949060509384;5130.743462826862;5140.023395554851;5044.672096313746;5102.668326558781;5153.149730294497;5160.974195129038;5164.457731938149;5031.723255220962;5109.2591574221715;5135.43510213878;5184.7148406837605;5200.791968321262;5171;5184.7148406837605;5215.217717342391;5276.92084618731;5282.3925248596515;5108.821191258326;5160.6129540284255;5216.739163041832;5226.216067589854;5259.947400525984;5361.544268737162;5420.728963551846;5438.401775804658;5498.065328894098;5542;5963.224780778514;5993.730282137314;6001.06983417571;6084.5132389408745;6086.147939288503;6117.5717699761035;6122.081687746829;6126.081087836816;6134.96932515339;6136.693165341715;5435.619506634537;5469.535089517396;5491.203775452577;5523;5542.0855558832345;6080.48315893154;6084.330723620398;6089.025755879126;6090.269167699905;6097.664628445478;5842.5618078644275;5847.181394604759;5848.093545500455;5853.473187413153;5855.180274761537;5703.258576385043;5760.855978600569;5926.377730338339;5943.613665111754;6036.607620504653;6045.486133678599;6045.667488288186;6055.424734650202;6070.332263451017;6089.360617135137;6021.969890150609;6043.758249669964;6060.727267272983;6067.45392914631;6072.635641861502;4486.484054333728;4508.594226519629;4611.100835337144;4668.789904454307;4670.673052886334;4114.629683328515;4127.442795222667;4148.128892932457;4154.688398370074;4182.351735481006;5815.73829177688;5817.0401883689565;5818.040023396177;5822.650640961556;5839.883202335929;5748.425157484218;5817.44734250254;6130.601510901866;6140.754369825247;6147.508199344042;5580.014692139685;5603.8879222416135;5610.158476228598;5643.887122257532;5676.8864622707315;5108.897822043538;5154.433012368637;5160.071187186268;5161.225816127611;5166.044281807877;5995.370486098896;6055.072420382515;6090.056041313604;6111.816645500642;6113.908291375633;5885.882282354414;6011.6693581853415;6083.087536869505;6104.938950610437;6129.053167990599;6239.06414037898;6303.905441418474;6319.368063193644;6325.114483972339;6326.683665816783;5990.161377407079;6018.067199584071;6021.849453763691;6023.789167379121;6026.789062382795;5813.476787089182;5913.763449461973;5929.229200203946;5937.614055086406;6069.817905462844;5880.970595146997;5891.941080589139;5893.793717219876;5896.882062358814;5931.347551769216;5869.236999190078;5869.589128760946;5871.6183448075735;5873.4126587340925;5875.030619947832;5731.226284451713;5784.537237020944;5828.6502809831545;5840.474357307863;5886.20536227621;5925.52595792327;5950.9702451487465;5971;6005.159277701038;6013.158157857816;5788;5823.7379317931645;5826;5831.1253312001845;5837.241158649348 04:04:23 INFO - PROCESS | 6696 | |1;ext.html;582.2314544800862;596.7583128832823;623.2707731953614;630.3161070238791;631.7662464887992;34570.30859382814;37743.24513509731;45422.772886135564;46601.53398466014;46715.53284467154;48418.51581484193;48860.267095993586;48930.26604600934;48969.755151224286;48993;9399.812003759938;9538.618455261781;9548.092931171528;9564.473953932531;9568.234541236685;8431.494110353367;8676.479411235347;11080.612178573752;11329.660110196708;11350.318980861175;11471.942640286787;11550.30698158109;11555.653330400102;11565.65303040906;11579.942100289487;10908.727281817939;10946.179036572288;10962;10963.50664220108;10963.56145754169;11123.16576256784;11152.721181970435;11173.888261117367;11187.440627968568;11196.888031119666;44535.55464445379;44909;45004;45054.77472612616;45099.32351014738;3582.695470884978;3598.1364472526666;3660.7071434285194;3875.360565506688;4577.382053422812;35483.64516354855;35559.4666080009;35787;36021;36519;4536.841210557648;4585.902778465177;4593.701409408378;4594.287885377772;4660.953390466119;250307;250825;250958;251607;252099.99999999817;65477;65700;65732.671336643;65794;65827.34172658308;40792.38811417831;41652;41795.58204417978;69498.30501694918;69516.65241673861;16367.42714005004;16460.6707865842;16479.752803707954;16504.83495165057;16522;11497.195196336343;11588.304701717923;12434.564790232298;12457.252564845958;12495.812562811565;20540.589188216152;21046.158153673987;21062;21676.674849877265;21827.345179644635;7440.590767507731;7650.147637057656;7678.923210767933;7743.303102720783;7859.371250299962;1007.7984403119406;1088.1729885287239;1135.8069128248217;1170.39724541861;1187.3825610682397;34345.65654343425;34678;35054.12364690903;35063.4740478893;35265.47101793475;1152.9077673786078;1174.6887074925153;1186.9643910682694;1196.238968903768;1217.9147459677915;18226.63546729058;18338.449846504627;18493.81506184921;18631.254749810127;18680.81319186818;570428;915810;925330;925991;938428;64234.67882660557;64705.35294646993;64887;64999.67500162469;65013.349866501674;1300.5578103444875;1335.218896945298;1391.2070120031485;1414.6463384153756;1415.2357726827397;32409.35181296408;35087.29825403529;35561.64438355687;37921.06747968245;38191.618083819914;61107.38892611017;61779.691101546;61866.38133618606;61875.38124618696;62051;534026;534741;539746;542207;542734;28015.299617509725;28801.279968000967;30841.8457907709;31797.523037154922;32492.350152997282;48868;50890.23664644959;51193.488065120364;51196.232056518435;51326;43531.347029795215;44804.55195448134;44819.77590112029;44868.775656121514;45788.54211457976;43655.78172109246;45114.77442612766;45510.08979820451;45536;45812;42608.36087458629;43437.782811085744;43813;43888;44748.10503789841;36268.818655907606;38446;39228;39358.212835743696;39961;29388.7061129386;29425.264368390963;29605.85197074001;29668.851655741582;29990.55014174833 04:04:23 INFO - PROCESS | 6696 | |2;jquery.html;410.38647222402494;416.7270437863197;425.05425428421756;426.10305307328076;441.7018512504059;19627.509312267182;20077.397678069643;20151;20396.388108356736;20423;17648;18543.25826966923;18585.814141858613;18650.813491865076;18789.530261743465;5669.886602267942;5830.387809280007;5861.443162899518;5881.882362352771;5904.350521442638;4148.274052040896;4423.535528769465;5789.102689083199;5855.79504717335;5873.500752436048;4453.087117140994;6436.549441539094;6527.869442611145;6529.314421985694;6534.54258201926;6307.621542707452;6440.259370172444;6498.415142637185;6512.837179070514;6515.250746164204;5792.420757924216;5793.188953546464;5794.21778059965;5799.623024503394;5801.593888427812;23355.7812007859;23861.403464913517;24037.879810600836;24175.637365439532;24408.877955610107;2631.7631413172717;2665.8667066646785;2768.5708715149185;2935.5596660500883;2971.2126286534085;14991.250437478082;15202.315895784606;15226.771598426032;15364;15459.458918937784;2056.9485762855898;2081.7814129516332;2094.937151885446;2098.779628139054;2127.5319429725437;63383;66610;71872;73172;82355.17644823594;10967.83548246777;11029.393383363831;11181.21731478805;11291.661250162508;11491.885081149248;15912.283947222402;15921.761173582405;15942.521724348288;15946.20268986546;15967.121808300422;8177.591120444073;8216.835663286702;8340.124286949904;8445.1977062179;8457.069722330538;7814.57019863913;7928.6035698214855;8086.353091752646;8149.185081491803;8167.346612271004;6756.831079223059;6885.762127308645;6901.137357830271;6940.6529673516125;6943.652817359213;1948.6005368899341;1990.2238127130431;2005.1778770704234;2038.9694154587694;2042.2749923777142;1029.696239609317;1065.7761870007384;1078.7626722121122;1101.7080473674482;1132.1791701016698;20493.590128197353;21329;21344.786552134592;21352.14591416326;21412.357629271148;1048.3919326790444;1062.049465728169;1076.7631121153333;1078.4823284823328;1082.6535508637319;10877.782444351227;11853.88146118545;12030.81953770694;12082.516699332104;12128.514859405701;472445;476658;477283;478531;481825;83256.58371708103;84349;84652.57673711592;85504.57247713722;85801;943.7357539888842;977.3207620703685;1036.259074761549;1044.1646682653848;1078.8651418572679;17966.371177008743;18084.72872906881;18327.725084124017;18459.907700461412;18855.528611784815;39893.0417613373;40002.1999560013;40865.79567102146;40958.385624215065;41514;54847.17729234145;56272.43727562672;58761;62504.374956249856;62864.485911754055;8680.218780309802;8740.038595754335;8783.121687831166;8895.644174232959;8950.507722075088;9594.18449431806;12859.16415432994;13224.537141200011;13474.258915759727;13730.93134534361;11282.323060616389;11362.14783891195;11799.17405781587;11960.76078478443;12030.27818330903;11289.435528223721;11292.096632269562;11452.94273528627;11519.424028798694;11528.250663706833;11322.207445479067;11865.347405892759;11961.64115076549;11999.76000480003;12068.577599784317;7784.377249820114;8273.627686753991;8634.438761480484;8821.250193733604;9300.20948219409;11909.940450297692;12053.337066461077;12141.089418293486;12299.446524906221;12354.691132721753 04:04:23 INFO - PROCESS | 6696 | |3;mootools.html;1488.9404423823044;1546.404634215827;1657.5276046326799;1765.9028753418572;1777.555611097225;8650.956745216274;9312.674056408026;9325.673601423952;9326.766830829232;9327.440353578782;7881.92118078819;8189.91810081899;8370.665173393058;8827.16141966512;8827.514486703229;2327.41814546363;2384.855269470655;2385.2963375804093;2385.427497400629;2392.964105538418;2105.494681276498;2386.140989243871;2392.629142482918;2427.4780922101745;2428.7935525480357;2196.1654571262898;2283.6003699352545;2351.482673811767;2351.7648235176516;2370.988145059281;2297.2304278066786;2302.873341966191;2304.147465437782;2304.7464778874396;2307.3885420363617;2348.3659411958674;2350.435895385095;2353.011735071257;2353.9411514712097;2355.0344358812977;8874.023857375683;8946.910530894673;9026.413283136575;9037.457752534803;9037.548122593844;5206.323177986837;5346.732663366816;5356.598255130881;5365.812196573102;5377.166539186433;3327.9168020799675;3379.6282408935;3491.690616018993;3501.509788629594;3525.435930251173;1190.4761904761876;1194.5764053840046;1200.0939290835363;1230.5323976888815;1236.1655882279515;12515;12759.553415630502;12821.551245706356;12904.677383065498;12906.483740650363;4493.1013797240685;4575.679702420865;4676.3453116563705;4793.784279707422;4796.25658023007;10687.625933092304;10815.405152716676;10816;10819.891801082045;10834.67495975122;5317.41508434072;5332.733363331896;5335.306410166653;5336.439673834269;5338.706371149547;4831.613470922388;4908.042931628298;4915.361003069578;4921.680090794087;4941.579965702956;4140.130572579732;4144.79276036197;4203.243416185116;4218.06052604152;4240.575942405735;1221.926684398939;1294.1393973008032;1316.5326309160303;1360.1838896661923;1373.5261334839465;743.5947408662292;982.4841957972122;1009.7324209084599;1014.7057353367588;1020.2959957629212;6178.104174894705;6238.1266622672865;6275.341089185661;6344.270408902943;6518.250401203827;865.7143142762873;892.1078921078921;897.0670502677273;898;913.3971578757988;4304.182205380967;4331.740095594275;4344.56554344563;4359.498657654411;4391.187630288343;10939.617113400993;11339.829902551468;11361;11369.488373023234;11369.715757106138;11333.26333788301;11334.773304533863;11338.09295256394;11344.773104537862;11346.886531134747;8040.437169398203;8375.204355586331;8399.328053755686;8542.615582298811;8706.6517339307;6120.816375508742;6181.289151747606;6292.874142517216;6307.05394190875;6321.589096708699;10170.491475426346;10406.739831504274;10524.31591946521;10640.201984851323;10665.146788257076;10329.793404132026;10533.210009249491;10544.103751181236;10599.523021464207;10799.082078023142;8142.348612110898;8185.672573097009;8325.084240733635;8325.625346859284;8329.416940814328;8651.437656552303;8675.132486751274;8692.174243446863;8721.258693011165;8755.868661969947;7683.308502234826;7886.447948643769;7946.8807967879375;7980.521168729895;7992.080910695112;7410.4442166836625;7636.579988100708;7727.690892364242;7777.805554861174;7972.92027079745;7086.645667716697;7236.059312289369;7243.058402407653;7248.492605517563;7249.565026098451;5332.30680011596;5384.326959130109;5398.190271459155;5414.269073675161;5497.340319161726;5397.217403476396;5436.456354364532;5462.317210348707;5537.8061767837935;5558.416366281483 04:04:23 INFO - PROCESS | 6696 | |4;prototype.html;337.9526866238725;344.23064450952126;347.36605694607346;351.75201482954526;353.803638980366;5198.350206224222;5364.597655175868;5399.973000134999;5431.592630552709;5450.100733378998;5186.222066689998;5287.915977224669;5337.345422918898;5338;5340.54605358545;4262.936055959163;4264.185637479264;4264.296391095466;4267.613025766638;4284.250256205151;4164.354525048622;4236.428082208909;4268.231718290706;4269.039466120129;4285.100128972919;4149.792510374469;4172.102997855461;4212.85255016073;4228.217779710763;4234.9200953756745;4193.119444916556;4200.222958752625;4203.47456567929;4209;4242.91514169718;3593.94609080864;3687.797171155585;3735.1595890924323;3736.645018723217;3795.335816232176;5228.085085110129;5228.215767634885;5236.554892834114;5250.133727934886;5251.789928402859;4826.903461930777;4840.467548569654;4853.708777473327;4862.756862156878;4873.293372461008;5410.431904649994;5551.694656793875;5569.192467092289;5597.292825688163;5643.209950606918;4049.635532802062;4086.366613174963;4086.9386959195635;4101.364288535252;4103.384492326145;5817.38917413674;5855.941440585625;5860.21639721129;5861.325947516048;5875.911861322126;4085.71400001997;4140.399642051885;4176.248275310473;4193;4211.410402543646;4242.151569686075;4249.638780703613;4249.80875860587;4252.958025283763;4253.383259427365;4827.25177597473;4832.371791667061;4833.226683730586;4837.564619184291;4839.661223714376;5411.377691565521;5648.830535083954;5657.462541058593;5660.943390566042;5704.458076482728;1382.6958069224754;1414.4554346576642;1416.6883285677134;1468;1468.6989167220695;980.8724865767547;989.3915242126185;998.0817647763929;1016.1413605503337;1016.9779371731363;5417.945820541744;5425.511703946664;5448.536874365724;5463.360991702473;5483.7806487740045;5654.180143879197;5675.943240567542;5690.943090569124;5691.857703557444;5696.3733989261145;5241.397239317451;5326.786928522815;5458.181272809111;5467.223152475462;5477.75350109246;2659.933501662474;2676.585129304922;2689.3411114276737;2713.552263876478;2715.6062370956497;5188.325517682677;5220.347456567929;5225.268462415265;5232.253067139706;5236.764345604457;4909.3126962225315;5078.238264260391;5084.41529224144;5156.63903526757;5201.45384734605;5148.3564554430695;5199.870003249874;5202.271681964528;5210.014339030645;5223.895522089613;4976.776045077908;5036.8519675203;5037.765747391913;5040.722760248222;5072.315237443046;4782.928256076231;4998.850034498971;5033.84898453047;5091.210862316347;5092.618053645917;5252.816151434681;5257.2913803014935;5262.658117137837;5271.89456210866;5277.815276465305;3777.658931079427;3814.332491813977;3826.4642949987583;3848.634379733921;3858.80705964695;4791.353167322367;5011.32347133147;5026.673266237683;5528.778848846001;5703.885922281448;5507.586930980113;5646.237757902631;5691.373948865705;5703.458171473703;5709.628874123168;5640.27971468706;5641.323041235078;5651.689157096399;5666.385080252108;5671.291088613923;5472.616916815785;5538.556915446674;5619.831405057855;5672.829815105553;5676.495728631833;5703.458171473703;5709.857253568694;5714.228579141763;5714.428557144252;5714.457126572969;5423.213634022965;5567.331920169444;5570.5265052470995;5571.777128914798;5596.580256480862 04:04:23 INFO - PROCESS | 6696 | |5;yui.html;782.2412260107698;807.7657479330994;857.5969294431617;866.7833041739565;927.3415874728942;15967.361305547778;17617.911910440445;17630.559236019075;17652;17656.470305890845;14891.25543722815;14953.626159346022;14956.32696528656;14983.25083745811;15093.169875656835;722.5484072454728;747.9326860582546;849.5709666618349;884.8628462588309;889.74642226965;876.4259410086404;878.7627340618029;879.6041781198454;880.1814312689196;881.8104107616861;759.1687102622619;914.9725508234764;921.0283151275402;922.796984663373;922.8615707643874;765.4641750774452;890.692711014699;905.3571963905634;915.6749353979375;922.9400088994261;673.7406098652054;676.5974245324073;676.7428377216676;677.478341676906;677.5325025732229;15006.324715387724;15011.24943752819;15024.924875375662;15032.849671503254;15090.924545377313;13207.933960330232;13234.66913327165;13263.27052012139;13572.524961626394;13836.446542138303;12407.937960310232;12462.252264864046;12557.937210313981;12818.551350702774;13003.479860805557;4530.818767249306;4546.727196368229;4617.330487079388;4778.761061946889;4784.808607655689;78003;83756.99999999939;84341;85102;85572.57213713953;11599.130065244972;11607.187496875138;11700.414979251003;11746.119041071954;11883.346415947033;20606.690899636516;20722.79277207239;20799;20804;20865.061072251785;8808.339374546935;8836.204741573161;8899.599518021705;8987.820243595092;9035.186833185046;4938.308636790852;4981.701097934136;4985.127602669519;5068.923966140437;5095.363079615048;3412;3503.4394496880377;3513.6662017108333;3516.2088530081037;3517.9472307915403;580.5529742098561;584.260909948915;586.8151532267408;596.8149873539217;607.8176547035872;683.1528904158807;691.2983321928247;720.2221600671262;727.1819203396179;737.5654352284862;9760.853587196198;9908.20734341251;10189.439580823126;10383.169346452265;10399.376037437778;690.4096997067535;693.3551796828897;696.7456878239406;698.1900994846055;721.1706537481833;4714.57568818808;4837.7097374157665;4920.991196804646;4932.802687892444;4974.253861920741;66839.66580167068;67984;69287.3071269291;69450;127955;59933;60157.39842601606;99858.500707496;103868.44197337046;105832.3541911443;91575;91591.54204228937;92550;93422.53288733512;93565;10087.798244035224;10635.680929572125;10736.624218152327;10830.729231769268;10961.780764384508;54845.1773223394;55102.44897551133;55798.44201557933;55860.44139558553;57495.1375729356;69246.26884327932;69302.30697692958;75301;83608;84439.15560844314;14205.218712970885;14246.35891384911;14504.202268874891;14535;14547.927260363631;15291.847081529042;15466.845331546541;15815.051096934221;16033.118178500294;16157.192140393168;13963.720725585634;14123.576292711236;14468.204248765998;14662.486812961495;15270.465533706267;14352.712945741236;14809.185494797459;14895.106293622417;14959.700805983603;16409.09749963716;14095.07593006931;14211.147331160164;14481.782773258621;14556.708865822837;14565.635859103608;11980.460879260629;12500.249985000566;12610.936945315214;12621.621351359474;13053.477860885458;11928.880711192776;12556;12598.307093109966;12850.293233872228;12995.935020325216 04:04:23 INFO - PROCESS | 6696 | __end_tp_report 04:04:23 INFO - PROCESS | 6696 | __start_cc_report 04:04:23 INFO - PROCESS | 6696 | _x_x_mozilla_cycle_collect,798 04:04:23 INFO - PROCESS | 6696 | __end_cc_report 04:04:23 INFO - PROCESS | 6696 | __startTimestamp1476529463797__endTimestamp 04:04:23 INFO - PROCESS | 6696 | 04:04:23 INFO - PROCESS | 6696 | ------- Summary: start ------- 04:04:23 INFO - PROCESS | 6696 | Number of tests: 6 04:04:23 INFO - PROCESS | 6696 | 04:04:23 INFO - PROCESS | 6696 | [#0] dojo.html Cycles:165 Average:5584.36 Median:5823.74 stddev:637.81 (11.0%) stddev-sans-first:610.40 04:04:23 INFO - PROCESS | 6696 | Values: 3145.8 3181.7 3191.2 3205.8 3221.6 5591.7 5636.9 5667.7 5672.4 5676.4 5533.6 5579.0 5581.1 5586.9 5605.2 5036.3 5058.6 5093.9 5130.7 5140.0 5044.7 5102.7 5153.1 5161.0 5164.5 5031.7 5109.3 5135.4 5184.7 5200.8 5171.0 5184.7 5215.2 5276.9 5282.4 5108.8 5160.6 5216.7 5226.2 5259.9 5361.5 5420.7 5438.4 5498.1 5542.0 5963.2 5993.7 6001.1 6084.5 6086.1 6117.6 6122.1 6126.1 6135.0 6136.7 5435.6 5469.5 5491.2 5523.0 5542.1 6080.5 6084.3 6089.0 6090.3 6097.7 5842.6 5847.2 5848.1 5853.5 5855.2 5703.3 5760.9 5926.4 5943.6 6036.6 6045.5 6045.7 6055.4 6070.3 6089.4 6022.0 6043.8 6060.7 6067.5 6072.6 4486.5 4508.6 4611.1 4668.8 4670.7 4114.6 4127.4 4148.1 4154.7 4182.4 5815.7 5817.0 5818.0 5822.7 5839.9 5748.4 5817.4 6130.6 6140.8 6147.5 5580.0 5603.9 5610.2 5643.9 5676.9 5108.9 5154.4 5160.1 5161.2 5166.0 5995.4 6055.1 6090.1 6111.8 6113.9 5885.9 6011.7 6083.1 6104.9 6129.1 6239.1 6303.9 6319.4 6325.1 6326.7 5990.2 6018.1 6021.8 6023.8 6026.8 5813.5 5913.8 5929.2 5937.6 6069.8 5881.0 5891.9 5893.8 5896.9 5931.3 5869.2 5869.6 5871.6 5873.4 5875.0 5731.2 5784.5 5828.7 5840.5 5886.2 5925.5 5951.0 5971.0 6005.2 6013.2 5788.0 5823.7 5826.0 5831.1 5837.2 04:04:23 INFO - PROCESS | 6696 | 04:04:23 INFO - PROCESS | 6696 | [#1] ext.html Cycles:180 Average:71040.10 Median:21551.68 stddev:163949.36 (760.7%) stddev-sans-first:164323.93 04:04:23 INFO - PROCESS | 6696 | Values: 582.2 596.8 623.3 630.3 631.8 34570.3 37743.2 45422.8 46601.5 46715.5 48418.5 48860.3 48930.3 48969.8 48993.0 9399.8 9538.6 9548.1 9564.5 9568.2 8431.5 8676.5 11080.6 11329.7 11350.3 11471.9 11550.3 11555.7 11565.7 11579.9 10908.7 10946.2 10962.0 10963.5 10963.6 11123.2 11152.7 11173.9 11187.4 11196.9 44535.6 44909.0 45004.0 45054.8 45099.3 3582.7 3598.1 3660.7 3875.4 4577.4 35483.6 35559.5 35787.0 36021.0 36519.0 4536.8 4585.9 4593.7 4594.3 4661.0 250307.0 250825.0 250958.0 251607.0 252100.0 65477.0 65700.0 65732.7 65794.0 65827.3 40792.4 41652.0 41795.6 69498.3 69516.7 16367.4 16460.7 16479.8 16504.8 16522.0 11497.2 11588.3 12434.6 12457.3 12495.8 20540.6 21046.2 21062.0 21676.7 21827.3 7440.6 7650.1 7678.9 7743.3 7859.4 1007.8 1088.2 1135.8 1170.4 1187.4 34345.7 34678.0 35054.1 35063.5 35265.5 1152.9 1174.7 1187.0 1196.2 1217.9 18226.6 18338.4 18493.8 18631.3 18680.8 570428.0 915810.0 925330.0 925991.0 938428.0 64234.7 64705.4 64887.0 64999.7 65013.3 1300.6 1335.2 1391.2 1414.6 1415.2 32409.4 35087.3 35561.6 37921.1 38191.6 61107.4 61779.7 61866.4 61875.4 62051.0 534026.0 534741.0 539746.0 542207.0 542734.0 28015.3 28801.3 30841.8 31797.5 32492.4 48868.0 50890.2 51193.5 51196.2 51326.0 43531.3 44804.6 44819.8 44868.8 45788.5 43655.8 45114.8 45510.1 45536.0 45812.0 42608.4 43437.8 43813.0 43888.0 44748.1 36268.8 38446.0 39228.0 39358.2 39961.0 29388.7 29425.3 29605.9 29668.9 29990.6 04:04:23 INFO - PROCESS | 6696 | 04:04:23 INFO - PROCESS | 6696 | [#2] jquery.html Cycles:180 Average:28601.38 Median:24292.26 stddev:78391.50 (322.7%) stddev-sans-first:78582.83 04:04:23 INFO - PROCESS | 6696 | Values: 410.4 416.7 425.1 426.1 441.7 19627.5 20077.4 20151.0 20396.4 20423.0 17648.0 18543.3 18585.8 18650.8 18789.5 5669.9 5830.4 5861.4 5881.9 5904.4 4148.3 4423.5 5789.1 5855.8 5873.5 4453.1 6436.5 6527.9 6529.3 6534.5 6307.6 6440.3 6498.4 6512.8 6515.3 5792.4 5793.2 5794.2 5799.6 5801.6 23355.8 23861.4 24037.9 24175.6 24408.9 2631.8 2665.9 2768.6 2935.6 2971.2 14991.3 15202.3 15226.8 15364.0 15459.5 2056.9 2081.8 2094.9 2098.8 2127.5 63383.0 66610.0 71872.0 73172.0 82355.2 10967.8 11029.4 11181.2 11291.7 11491.9 15912.3 15921.8 15942.5 15946.2 15967.1 8177.6 8216.8 8340.1 8445.2 8457.1 7814.6 7928.6 8086.4 8149.2 8167.3 6756.8 6885.8 6901.1 6940.7 6943.7 1948.6 1990.2 2005.2 2039.0 2042.3 1029.7 1065.8 1078.8 1101.7 1132.2 20493.6 21329.0 21344.8 21352.1 21412.4 1048.4 1062.0 1076.8 1078.5 1082.7 10877.8 11853.9 12030.8 12082.5 12128.5 472445.0 476658.0 477283.0 478531.0 481825.0 83256.6 84349.0 84652.6 85504.6 85801.0 943.7 977.3 1036.3 1044.2 1078.9 17966.4 18084.7 18327.7 18459.9 18855.5 39893.0 40002.2 40865.8 40958.4 41514.0 54847.2 56272.4 58761.0 62504.4 62864.5 8680.2 8740.0 8783.1 8895.6 8950.5 9594.2 12859.2 13224.5 13474.3 13730.9 11282.3 11362.1 11799.2 11960.8 12030.3 11289.4 11292.1 11452.9 11519.4 11528.3 11322.2 11865.3 11961.6 11999.8 12068.6 7784.4 8273.6 8634.4 8821.3 9300.2 11909.9 12053.3 12141.1 12299.4 12354.7 04:04:23 INFO - PROCESS | 6696 | 04:04:23 INFO - PROCESS | 6696 | [#3] mootools.html Cycles:180 Average:5982.71 Median:4735.06 stddev:3414.99 (72.1%) stddev-sans-first:3407.87 04:04:23 INFO - PROCESS | 6696 | Values: 1488.9 1546.4 1657.5 1765.9 1777.6 8651.0 9312.7 9325.7 9326.8 9327.4 7881.9 8189.9 8370.7 8827.2 8827.5 2327.4 2384.9 2385.3 2385.4 2393.0 2105.5 2386.1 2392.6 2427.5 2428.8 2196.2 2283.6 2351.5 2351.8 2371.0 2297.2 2302.9 2304.1 2304.7 2307.4 2348.4 2350.4 2353.0 2353.9 2355.0 8874.0 8946.9 9026.4 9037.5 9037.5 5206.3 5346.7 5356.6 5365.8 5377.2 3327.9 3379.6 3491.7 3501.5 3525.4 1190.5 1194.6 1200.1 1230.5 1236.2 12515.0 12759.6 12821.6 12904.7 12906.5 4493.1 4575.7 4676.3 4793.8 4796.3 10687.6 10815.4 10816.0 10819.9 10834.7 5317.4 5332.7 5335.3 5336.4 5338.7 4831.6 4908.0 4915.4 4921.7 4941.6 4140.1 4144.8 4203.2 4218.1 4240.6 1221.9 1294.1 1316.5 1360.2 1373.5 743.6 982.5 1009.7 1014.7 1020.3 6178.1 6238.1 6275.3 6344.3 6518.3 865.7 892.1 897.1 898.0 913.4 4304.2 4331.7 4344.6 4359.5 4391.2 10939.6 11339.8 11361.0 11369.5 11369.7 11333.3 11334.8 11338.1 11344.8 11346.9 8040.4 8375.2 8399.3 8542.6 8706.7 6120.8 6181.3 6292.9 6307.1 6321.6 10170.5 10406.7 10524.3 10640.2 10665.1 10329.8 10533.2 10544.1 10599.5 10799.1 8142.3 8185.7 8325.1 8325.6 8329.4 8651.4 8675.1 8692.2 8721.3 8755.9 7683.3 7886.4 7946.9 7980.5 7992.1 7410.4 7636.6 7727.7 7777.8 7972.9 7086.6 7236.1 7243.1 7248.5 7249.6 5332.3 5384.3 5398.2 5414.3 5497.3 5397.2 5436.5 5462.3 5537.8 5558.4 04:04:23 INFO - PROCESS | 6696 | 04:04:23 INFO - PROCESS | 6696 | [#4] prototype.html Cycles:180 Average:4603.75 Median:5187.27 stddev:1322.28 (25.5%) stddev-sans-first:1286.64 04:04:23 INFO - PROCESS | 6696 | Values: 338.0 344.2 347.4 351.8 353.8 5198.4 5364.6 5400.0 5431.6 5450.1 5186.2 5287.9 5337.3 5338.0 5340.5 4262.9 4264.2 4264.3 4267.6 4284.3 4164.4 4236.4 4268.2 4269.0 4285.1 4149.8 4172.1 4212.9 4228.2 4234.9 4193.1 4200.2 4203.5 4209.0 4242.9 3593.9 3687.8 3735.2 3736.6 3795.3 5228.1 5228.2 5236.6 5250.1 5251.8 4826.9 4840.5 4853.7 4862.8 4873.3 5410.4 5551.7 5569.2 5597.3 5643.2 4049.6 4086.4 4086.9 4101.4 4103.4 5817.4 5855.9 5860.2 5861.3 5875.9 4085.7 4140.4 4176.2 4193.0 4211.4 4242.2 4249.6 4249.8 4253.0 4253.4 4827.3 4832.4 4833.2 4837.6 4839.7 5411.4 5648.8 5657.5 5660.9 5704.5 1382.7 1414.5 1416.7 1468.0 1468.7 980.9 989.4 998.1 1016.1 1017.0 5417.9 5425.5 5448.5 5463.4 5483.8 5654.2 5675.9 5690.9 5691.9 5696.4 5241.4 5326.8 5458.2 5467.2 5477.8 2659.9 2676.6 2689.3 2713.6 2715.6 5188.3 5220.3 5225.3 5232.3 5236.8 4909.3 5078.2 5084.4 5156.6 5201.5 5148.4 5199.9 5202.3 5210.0 5223.9 4976.8 5036.9 5037.8 5040.7 5072.3 4782.9 4998.9 5033.8 5091.2 5092.6 5252.8 5257.3 5262.7 5271.9 5277.8 3777.7 3814.3 3826.5 3848.6 3858.8 4791.4 5011.3 5026.7 5528.8 5703.9 5507.6 5646.2 5691.4 5703.5 5709.6 5640.3 5641.3 5651.7 5666.4 5671.3 5472.6 5538.6 5619.8 5672.8 5676.5 5703.5 5709.9 5714.2 5714.4 5714.5 5423.2 5567.3 5570.5 5571.8 5596.6 04:04:23 INFO - PROCESS | 6696 | 04:04:23 INFO - PROCESS | 6696 | [#5] yui.html Cycles:180 Average:20393.55 Median:4781.78 stddev:27893.32 (583.3%) stddev-sans-first:27932.70 04:04:23 INFO - PROCESS | 6696 | Values: 782.2 807.8 857.6 866.8 927.3 15967.4 17617.9 17630.6 17652.0 17656.5 14891.3 14953.6 14956.3 14983.3 15093.2 722.5 747.9 849.6 884.9 889.7 876.4 878.8 879.6 880.2 881.8 759.2 915.0 921.0 922.8 922.9 765.5 890.7 905.4 915.7 922.9 673.7 676.6 676.7 677.5 677.5 15006.3 15011.2 15024.9 15032.8 15090.9 13207.9 13234.7 13263.3 13572.5 13836.4 12407.9 12462.3 12557.9 12818.6 13003.5 4530.8 4546.7 4617.3 4778.8 4784.8 78003.0 83757.0 84341.0 85102.0 85572.6 11599.1 11607.2 11700.4 11746.1 11883.3 20606.7 20722.8 20799.0 20804.0 20865.1 8808.3 8836.2 8899.6 8987.8 9035.2 4938.3 4981.7 4985.1 5068.9 5095.4 3412.0 3503.4 3513.7 3516.2 3517.9 580.6 584.3 586.8 596.8 607.8 683.2 691.3 720.2 727.2 737.6 9760.9 9908.2 10189.4 10383.2 10399.4 690.4 693.4 696.7 698.2 721.2 4714.6 4837.7 4921.0 4932.8 4974.3 66839.7 67984.0 69287.3 69450.0 127955.0 59933.0 60157.4 99858.5 103868.4 105832.4 91575.0 91591.5 92550.0 93422.5 93565.0 10087.8 10635.7 10736.6 10830.7 10961.8 54845.2 55102.4 55798.4 55860.4 57495.1 69246.3 69302.3 75301.0 83608.0 84439.2 14205.2 14246.4 14504.2 14535.0 14547.9 15291.8 15466.8 15815.1 16033.1 16157.2 13963.7 14123.6 14468.2 14662.5 15270.5 14352.7 14809.2 14895.1 14959.7 16409.1 14095.1 14211.1 14481.8 14556.7 14565.6 11980.5 12500.2 12610.9 12621.6 13053.5 11928.9 12556.0 12598.3 12850.3 12995.9 04:04:23 INFO - PROCESS | 6696 | -------- Summary: end -------- 04:04:23 INFO - PROCESS | 6696 | 04:04:24 INFO - TEST-INFO | 6696: exit 0 04:04:24 INFO - TEST-OK | dromaeo_css | took 1113641ms 04:04:24 INFO - TEST-START | kraken 04:04:24 INFO - operating with platform_type : linux_ 04:04:24 INFO - Initialising browser for kraken test... 04:04:24 INFO - TEST-INFO | started process 7016 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjwDxOH/profile http://localhost:60343/getInfo.html) 04:04:28 INFO - PROCESS | 7016 | __metrics Screen width/height:1600/1200 04:04:28 INFO - PROCESS | 7016 | colorDepth:24 04:04:28 INFO - PROCESS | 7016 | Browser inner width/height: 1024/697 04:04:28 INFO - PROCESS | 7016 | __metrics 04:04:29 INFO - PROCESS | 7016 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:04:29 INFO - PROCESS | 7016 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:04:29 INFO - PROCESS | 7016 | console.error: PushService: 04:04:29 INFO - PROCESS | 7016 | stateChangeProcessEnqueue: Error transitioning state 04:04:29 INFO - PROCESS | 7016 | UnknownError 04:04:30 INFO - TEST-INFO | 7016: exit 0 04:04:30 INFO - Browser initialized. 04:04:30 INFO - Running cycle 1/1 for kraken test... 04:04:30 INFO - TEST-INFO | started process 7107 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjwDxOH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:04:42 INFO - PROCESS | 7107 | RSS: Main: 144449536 04:04:42 INFO - PROCESS | 7107 | 04:04:44 INFO - PROCESS | 7107 | Cycle 1(1): loaded http://localhost:60343/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60343/tests/kraken/driver.html?testName=audio-beat-detection) 04:04:45 INFO - PROCESS | 7107 | RSS: Main: 185769984 04:04:45 INFO - PROCESS | 7107 | 04:04:48 INFO - PROCESS | 7107 | Cycle 1(1): loaded http://localhost:60343/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60343/tests/kraken/driver.html?testName=audio-dft) 04:04:48 INFO - PROCESS | 7107 | RSS: Main: 175525888 04:04:48 INFO - PROCESS | 7107 | 04:04:51 INFO - PROCESS | 7107 | Cycle 1(1): loaded http://localhost:60343/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60343/tests/kraken/driver.html?testName=audio-fft) 04:04:52 INFO - PROCESS | 7107 | RSS: Main: 267620352 04:04:52 INFO - PROCESS | 7107 | 04:04:54 INFO - PROCESS | 7107 | Cycle 1(1): loaded http://localhost:60343/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60343/tests/kraken/driver.html?testName=audio-oscillator) 04:04:54 INFO - PROCESS | 7107 | RSS: Main: 173871104 04:04:54 INFO - PROCESS | 7107 | 04:04:56 INFO - PROCESS | 7107 | Cycle 1(1): loaded http://localhost:60343/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60343/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:04:57 INFO - PROCESS | 7107 | RSS: Main: 175456256 04:04:57 INFO - PROCESS | 7107 | 04:05:00 INFO - PROCESS | 7107 | Cycle 1(1): loaded http://localhost:60343/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60343/tests/kraken/driver.html?testName=imaging-darkroom) 04:05:01 INFO - PROCESS | 7107 | RSS: Main: 210956288 04:05:01 INFO - PROCESS | 7107 | 04:05:04 INFO - PROCESS | 7107 | Cycle 1(1): loaded http://localhost:60343/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60343/tests/kraken/driver.html?testName=imaging-desaturate) 04:05:05 INFO - PROCESS | 7107 | RSS: Main: 210030592 04:05:05 INFO - PROCESS | 7107 | 04:05:07 INFO - PROCESS | 7107 | Cycle 1(1): loaded http://localhost:60343/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60343/tests/kraken/driver.html?testName=json-parse-financial) 04:05:08 INFO - PROCESS | 7107 | RSS: Main: 209031168 04:05:08 INFO - PROCESS | 7107 | 04:05:09 INFO - PROCESS | 7107 | Cycle 1(1): loaded http://localhost:60343/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60343/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:05:10 INFO - PROCESS | 7107 | RSS: Main: 171241472 04:05:10 INFO - PROCESS | 7107 | 04:05:12 INFO - PROCESS | 7107 | Cycle 1(1): loaded http://localhost:60343/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60343/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:05:12 INFO - PROCESS | 7107 | RSS: Main: 178610176 04:05:12 INFO - PROCESS | 7107 | 04:05:14 INFO - PROCESS | 7107 | Cycle 1(1): loaded http://localhost:60343/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60343/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:05:15 INFO - PROCESS | 7107 | RSS: Main: 188608512 04:05:15 INFO - PROCESS | 7107 | 04:05:17 INFO - PROCESS | 7107 | Cycle 1(1): loaded http://localhost:60343/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60343/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:05:18 INFO - PROCESS | 7107 | RSS: Main: 196435968 04:05:18 INFO - PROCESS | 7107 | 04:05:21 INFO - PROCESS | 7107 | Cycle 1(1): loaded http://localhost:60343/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60343/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:05:21 INFO - PROCESS | 7107 | RSS: Main: 193363968 04:05:21 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | Cycle 1(1): loaded http://localhost:60343/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60343/tests/kraken/driver.html?testName=ai-astar) 04:05:23 INFO - PROCESS | 7107 | RSS: Main: 245121024 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | __start_tp_report 04:05:23 INFO - PROCESS | 7107 | _x_x_mozilla_page_load 04:05:23 INFO - PROCESS | 7107 | _x_x_mozilla_page_load_details 04:05:23 INFO - PROCESS | 7107 | |i|pagename|runs| 04:05:23 INFO - PROCESS | 7107 | |0;ai-astar;110;113;111;111;111;112;111;111;111;110 04:05:23 INFO - PROCESS | 7107 | |1;audio-beat-detection;145;119;119;167;121;122;116;122;161;118 04:05:23 INFO - PROCESS | 7107 | |2;audio-dft;160;162;160;160;160;162;161;160;159;160 04:05:23 INFO - PROCESS | 7107 | |3;audio-fft;75;76;75;111;76;74;75;75;96;75 04:05:23 INFO - PROCESS | 7107 | |4;audio-oscillator;81;81;119;81;81;81;107;82;81;109 04:05:23 INFO - PROCESS | 7107 | |5;imaging-gaussian-blur;117;117;117;117;117;117;116;117;116;118 04:05:23 INFO - PROCESS | 7107 | |6;imaging-darkroom;146;146;146;147;146;146;146;145;146;147 04:05:23 INFO - PROCESS | 7107 | |7;imaging-desaturate;95;95;102;95;95;95;95;96;95;94 04:05:23 INFO - PROCESS | 7107 | |8;json-parse-financial;50;49;51;52;50;50;51;50;51;50 04:05:23 INFO - PROCESS | 7107 | |9;json-stringify-tinderbox;59;60;58;60;58;59;58;58;59;58 04:05:23 INFO - PROCESS | 7107 | |10;stanford-crypto-aes;76;73;73;74;72;75;76;73;79;75 04:05:23 INFO - PROCESS | 7107 | |11;stanford-crypto-ccm;126;120;119;123;128;122;134;116;158;129 04:05:23 INFO - PROCESS | 7107 | |12;stanford-crypto-pbkdf2;156;157;156;154;159;158;160;163;156;156 04:05:23 INFO - PROCESS | 7107 | |13;stanford-crypto-sha256-iterative;66;63;67;62;62;63;62;62;63;62 04:05:23 INFO - PROCESS | 7107 | __end_tp_report 04:05:23 INFO - PROCESS | 7107 | __start_cc_report 04:05:23 INFO - PROCESS | 7107 | _x_x_mozilla_cycle_collect,1576 04:05:23 INFO - PROCESS | 7107 | __end_cc_report 04:05:23 INFO - PROCESS | 7107 | __startTimestamp1476529523735__endTimestamp 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | ------- Summary: start ------- 04:05:23 INFO - PROCESS | 7107 | Number of tests: 14 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.00 stddev:0.88 (0.8%) stddev-sans-first:0.83 04:05:23 INFO - PROCESS | 7107 | Values: 110.0 113.0 111.0 111.0 111.0 112.0 111.0 111.0 111.0 110.0 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | [#1] audio-beat-detection Cycles:10 Average:131.00 Median:122.00 stddev:19.25 (15.8%) stddev-sans-first:19.74 04:05:23 INFO - PROCESS | 7107 | Values: 145.0 119.0 119.0 167.0 121.0 122.0 116.0 122.0 161.0 118.0 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 04:05:23 INFO - PROCESS | 7107 | Values: 160.0 162.0 160.0 160.0 160.0 162.0 161.0 160.0 159.0 160.0 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | [#3] audio-fft Cycles:10 Average:80.80 Median:75.00 stddev:12.49 (16.7%) stddev-sans-first:13.07 04:05:23 INFO - PROCESS | 7107 | Values: 75.0 76.0 75.0 111.0 76.0 74.0 75.0 75.0 96.0 75.0 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:81.00 stddev:15.06 (18.6%) stddev-sans-first:15.59 04:05:23 INFO - PROCESS | 7107 | Values: 81.0 81.0 119.0 81.0 81.0 81.0 107.0 82.0 81.0 109.0 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 04:05:23 INFO - PROCESS | 7107 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 118.0 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 04:05:23 INFO - PROCESS | 7107 | Values: 146.0 146.0 146.0 147.0 146.0 146.0 146.0 145.0 146.0 147.0 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:95.00 stddev:2.26 (2.4%) stddev-sans-first:2.39 04:05:23 INFO - PROCESS | 7107 | Values: 95.0 95.0 102.0 95.0 95.0 95.0 95.0 96.0 95.0 94.0 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.50 stddev:0.84 (1.7%) stddev-sans-first:0.88 04:05:23 INFO - PROCESS | 7107 | Values: 50.0 49.0 51.0 52.0 50.0 50.0 51.0 50.0 51.0 50.0 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:0.82 (1.4%) stddev-sans-first:0.87 04:05:23 INFO - PROCESS | 7107 | Values: 59.0 60.0 58.0 60.0 58.0 59.0 58.0 58.0 59.0 58.0 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:75.00 stddev:2.07 (2.8%) stddev-sans-first:2.13 04:05:23 INFO - PROCESS | 7107 | Values: 76.0 73.0 73.0 74.0 72.0 75.0 76.0 73.0 79.0 75.0 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | [#11] stanford-crypto-ccm Cycles:10 Average:127.50 Median:127.00 stddev:11.97 (9.4%) stddev-sans-first:12.68 04:05:23 INFO - PROCESS | 7107 | Values: 126.0 120.0 119.0 123.0 128.0 122.0 134.0 116.0 158.0 129.0 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.50 Median:157.50 stddev:2.59 (1.6%) stddev-sans-first:2.69 04:05:23 INFO - PROCESS | 7107 | Values: 156.0 157.0 156.0 154.0 159.0 158.0 160.0 163.0 156.0 156.0 04:05:23 INFO - PROCESS | 7107 | 04:05:23 INFO - PROCESS | 7107 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:1.81 (2.9%) stddev-sans-first:1.62 04:05:23 INFO - PROCESS | 7107 | Values: 66.0 63.0 67.0 62.0 62.0 63.0 62.0 62.0 63.0 62.0 04:05:23 INFO - PROCESS | 7107 | -------- Summary: end -------- 04:05:23 INFO - PROCESS | 7107 | 04:05:24 INFO - TEST-INFO | 7107: exit 0 04:05:24 INFO - TEST-OK | kraken | took 60261ms 04:05:24 INFO - SUITE-END | took 1173s 04:05:24 INFO - Completed test suite (00:19:34) 04:05:24 INFO - javascript benchmark 04:05:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3145.81125132492, 3181.6500184979664, 3191.2341038150844, 3205.8397080145987, 3221.6295126060495, 5591.7483713232905, 5636.887262254758, 5667.744951477183, 5672.404397538261, 5676.375598684142, 5533.584981126412, 5579.023670857604, 5581.107022876341, 5586.888262234753, 5605.1872478490595, 5036.2949187113745, 5058.59531237502, 5093.949060509384, 5130.743462826862, 5140.023395554851, 5044.672096313746, 5102.668326558781, 5153.149730294497, 5160.974195129038, 5164.457731938149, 5031.723255220962, 5109.2591574221715, 5135.43510213878, 5184.7148406837605, 5200.791968321262, 5171.0, 5184.7148406837605, 5215.217717342391, 5276.92084618731, 5282.3925248596515, 5108.821191258326, 5160.6129540284255, 5216.739163041832, 5226.216067589854, 5259.947400525984, 5361.544268737162, 5420.728963551846, 5438.401775804658, 5498.065328894098, 5542.0, 5963.224780778514, 5993.730282137314, 6001.06983417571, 6084.5132389408745, 6086.147939288503, 6117.5717699761035, 6122.081687746829, 6126.081087836816, 6134.96932515339, 6136.693165341715, 5435.619506634537, 5469.535089517396, 5491.203775452577, 5523.0, 5542.0855558832345, 6080.48315893154, 6084.330723620398, 6089.025755879126, 6090.269167699905, 6097.664628445478, 5842.5618078644275, 5847.181394604759, 5848.093545500455, 5853.473187413153, 5855.180274761537, 5703.258576385043, 5760.855978600569, 5926.377730338339, 5943.613665111754, 6036.607620504653, 6045.486133678599, 6045.667488288186, 6055.424734650202, 6070.332263451017, 6089.360617135137, 6021.969890150609, 6043.758249669964, 6060.727267272983, 6067.45392914631, 6072.635641861502, 4486.484054333728, 4508.594226519629, 4611.100835337144, 4668.789904454307, 4670.673052886334, 4114.629683328515, 4127.442795222667, 4148.128892932457, 4154.688398370074, 4182.351735481006, 5815.73829177688, 5817.0401883689565, 5818.040023396177, 5822.650640961556, 5839.883202335929, 5748.425157484218, 5817.44734250254, 6130.601510901866, 6140.754369825247, 6147.508199344042, 5580.014692139685, 5603.8879222416135, 5610.158476228598, 5643.887122257532, 5676.8864622707315, 5108.897822043538, 5154.433012368637, 5160.071187186268, 5161.225816127611, 5166.044281807877, 5995.370486098896, 6055.072420382515, 6090.056041313604, 6111.816645500642, 6113.908291375633, 5885.882282354414, 6011.6693581853415, 6083.087536869505, 6104.938950610437, 6129.053167990599, 6239.06414037898, 6303.905441418474, 6319.368063193644, 6325.114483972339, 6326.683665816783, 5990.161377407079, 6018.067199584071, 6021.849453763691, 6023.789167379121, 6026.789062382795, 5813.476787089182, 5913.763449461973, 5929.229200203946, 5937.614055086406, 6069.817905462844, 5880.970595146997, 5891.941080589139, 5893.793717219876, 5896.882062358814, 5931.347551769216, 5869.236999190078, 5869.589128760946, 5871.6183448075735, 5873.4126587340925, 5875.030619947832, 5731.226284451713, 5784.537237020944, 5828.6502809831545, 5840.474357307863, 5886.20536227621, 5925.52595792327, 5950.9702451487465, 5971.0, 6005.159277701038, 6013.158157857816, 5788.0, 5823.7379317931645, 5826.0, 5831.1253312001845, 5837.241158649348], "value": 5540.692784569119, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.2314544800862, 596.7583128832823, 623.2707731953614, 630.3161070238791, 631.7662464887992, 34570.30859382814, 37743.24513509731, 45422.772886135564, 46601.53398466014, 46715.53284467154, 48418.51581484193, 48860.267095993586, 48930.26604600934, 48969.755151224286, 48993.0, 9399.812003759938, 9538.618455261781, 9548.092931171528, 9564.473953932531, 9568.234541236685, 8431.494110353367, 8676.479411235347, 11080.612178573752, 11329.660110196708, 11350.318980861175, 11471.942640286787, 11550.30698158109, 11555.653330400102, 11565.65303040906, 11579.942100289487, 10908.727281817939, 10946.179036572288, 10962.0, 10963.50664220108, 10963.56145754169, 11123.16576256784, 11152.721181970435, 11173.888261117367, 11187.440627968568, 11196.888031119666, 44535.55464445379, 44909.0, 45004.0, 45054.77472612616, 45099.32351014738, 3582.695470884978, 3598.1364472526666, 3660.7071434285194, 3875.360565506688, 4577.382053422812, 35483.64516354855, 35559.4666080009, 35787.0, 36021.0, 36519.0, 4536.841210557648, 4585.902778465177, 4593.701409408378, 4594.287885377772, 4660.953390466119, 250307.0, 250825.0, 250958.0, 251607.0, 252099.99999999817, 65477.0, 65700.0, 65732.671336643, 65794.0, 65827.34172658308, 40792.38811417831, 41652.0, 41795.58204417978, 69498.30501694918, 69516.65241673861, 16367.42714005004, 16460.6707865842, 16479.752803707954, 16504.83495165057, 16522.0, 11497.195196336343, 11588.304701717923, 12434.564790232298, 12457.252564845958, 12495.812562811565, 20540.589188216152, 21046.158153673987, 21062.0, 21676.674849877265, 21827.345179644635, 7440.590767507731, 7650.147637057656, 7678.923210767933, 7743.303102720783, 7859.371250299962, 1007.7984403119406, 1088.1729885287239, 1135.8069128248217, 1170.39724541861, 1187.3825610682397, 34345.65654343425, 34678.0, 35054.12364690903, 35063.4740478893, 35265.47101793475, 1152.9077673786078, 1174.6887074925153, 1186.9643910682694, 1196.238968903768, 1217.9147459677915, 18226.63546729058, 18338.449846504627, 18493.81506184921, 18631.254749810127, 18680.81319186818, 570428.0, 915810.0, 925330.0, 925991.0, 938428.0, 64234.67882660557, 64705.35294646993, 64887.0, 64999.67500162469, 65013.349866501674, 1300.5578103444875, 1335.218896945298, 1391.2070120031485, 1414.6463384153756, 1415.2357726827397, 32409.35181296408, 35087.29825403529, 35561.64438355687, 37921.06747968245, 38191.618083819914, 61107.38892611017, 61779.691101546, 61866.38133618606, 61875.38124618696, 62051.0, 534026.0, 534741.0, 539746.0, 542207.0, 542734.0, 28015.299617509725, 28801.279968000967, 30841.8457907709, 31797.523037154922, 32492.350152997282, 48868.0, 50890.23664644959, 51193.488065120364, 51196.232056518435, 51326.0, 43531.347029795215, 44804.55195448134, 44819.77590112029, 44868.775656121514, 45788.54211457976, 43655.78172109246, 45114.77442612766, 45510.08979820451, 45536.0, 45812.0, 42608.36087458629, 43437.782811085744, 43813.0, 43888.0, 44748.10503789841, 36268.818655907606, 38446.0, 39228.0, 39358.212835743696, 39961.0, 29388.7061129386, 29425.264368390963, 29605.85197074001, 29668.851655741582, 29990.55014174833], "value": 21857.89291556975, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.38647222402494, 416.7270437863197, 425.05425428421756, 426.10305307328076, 441.7018512504059, 19627.509312267182, 20077.397678069643, 20151.0, 20396.388108356736, 20423.0, 17648.0, 18543.25826966923, 18585.814141858613, 18650.813491865076, 18789.530261743465, 5669.886602267942, 5830.387809280007, 5861.443162899518, 5881.882362352771, 5904.350521442638, 4148.274052040896, 4423.535528769465, 5789.102689083199, 5855.79504717335, 5873.500752436048, 4453.087117140994, 6436.549441539094, 6527.869442611145, 6529.314421985694, 6534.54258201926, 6307.621542707452, 6440.259370172444, 6498.415142637185, 6512.837179070514, 6515.250746164204, 5792.420757924216, 5793.188953546464, 5794.21778059965, 5799.623024503394, 5801.593888427812, 23355.7812007859, 23861.403464913517, 24037.879810600836, 24175.637365439532, 24408.877955610107, 2631.7631413172717, 2665.8667066646785, 2768.5708715149185, 2935.5596660500883, 2971.2126286534085, 14991.250437478082, 15202.315895784606, 15226.771598426032, 15364.0, 15459.458918937784, 2056.9485762855898, 2081.7814129516332, 2094.937151885446, 2098.779628139054, 2127.5319429725437, 63383.0, 66610.0, 71872.0, 73172.0, 82355.17644823594, 10967.83548246777, 11029.393383363831, 11181.21731478805, 11291.661250162508, 11491.885081149248, 15912.283947222402, 15921.761173582405, 15942.521724348288, 15946.20268986546, 15967.121808300422, 8177.591120444073, 8216.835663286702, 8340.124286949904, 8445.1977062179, 8457.069722330538, 7814.57019863913, 7928.6035698214855, 8086.353091752646, 8149.185081491803, 8167.346612271004, 6756.831079223059, 6885.762127308645, 6901.137357830271, 6940.6529673516125, 6943.652817359213, 1948.6005368899341, 1990.2238127130431, 2005.1778770704234, 2038.9694154587694, 2042.2749923777142, 1029.696239609317, 1065.7761870007384, 1078.7626722121122, 1101.7080473674482, 1132.1791701016698, 20493.590128197353, 21329.0, 21344.786552134592, 21352.14591416326, 21412.357629271148, 1048.3919326790444, 1062.049465728169, 1076.7631121153333, 1078.4823284823328, 1082.6535508637319, 10877.782444351227, 11853.88146118545, 12030.81953770694, 12082.516699332104, 12128.514859405701, 472445.0, 476658.0, 477283.0, 478531.0, 481825.0, 83256.58371708103, 84349.0, 84652.57673711592, 85504.57247713722, 85801.0, 943.7357539888842, 977.3207620703685, 1036.259074761549, 1044.1646682653848, 1078.8651418572679, 17966.371177008743, 18084.72872906881, 18327.725084124017, 18459.907700461412, 18855.528611784815, 39893.0417613373, 40002.1999560013, 40865.79567102146, 40958.385624215065, 41514.0, 54847.17729234145, 56272.43727562672, 58761.0, 62504.374956249856, 62864.485911754055, 8680.218780309802, 8740.038595754335, 8783.121687831166, 8895.644174232959, 8950.507722075088, 9594.18449431806, 12859.16415432994, 13224.537141200011, 13474.258915759727, 13730.93134534361, 11282.323060616389, 11362.14783891195, 11799.17405781587, 11960.76078478443, 12030.27818330903, 11289.435528223721, 11292.096632269562, 11452.94273528627, 11519.424028798694, 11528.250663706833, 11322.207445479067, 11865.347405892759, 11961.64115076549, 11999.76000480003, 12068.577599784317, 7784.377249820114, 8273.627686753991, 8634.438761480484, 8821.250193733604, 9300.20948219409, 11909.940450297692, 12053.337066461077, 12141.089418293486, 12299.446524906221, 12354.691132721753], "value": 9635.897985905554, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1488.9404423823044, 1546.404634215827, 1657.5276046326799, 1765.9028753418572, 1777.555611097225, 8650.956745216274, 9312.674056408026, 9325.673601423952, 9326.766830829232, 9327.440353578782, 7881.92118078819, 8189.91810081899, 8370.665173393058, 8827.16141966512, 8827.514486703229, 2327.41814546363, 2384.855269470655, 2385.2963375804093, 2385.427497400629, 2392.964105538418, 2105.494681276498, 2386.140989243871, 2392.629142482918, 2427.4780922101745, 2428.7935525480357, 2196.1654571262898, 2283.6003699352545, 2351.482673811767, 2351.7648235176516, 2370.988145059281, 2297.2304278066786, 2302.873341966191, 2304.147465437782, 2304.7464778874396, 2307.3885420363617, 2348.3659411958674, 2350.435895385095, 2353.011735071257, 2353.9411514712097, 2355.0344358812977, 8874.023857375683, 8946.910530894673, 9026.413283136575, 9037.457752534803, 9037.548122593844, 5206.323177986837, 5346.732663366816, 5356.598255130881, 5365.812196573102, 5377.166539186433, 3327.9168020799675, 3379.6282408935, 3491.690616018993, 3501.509788629594, 3525.435930251173, 1190.4761904761876, 1194.5764053840046, 1200.0939290835363, 1230.5323976888815, 1236.1655882279515, 12515.0, 12759.553415630502, 12821.551245706356, 12904.677383065498, 12906.483740650363, 4493.1013797240685, 4575.679702420865, 4676.3453116563705, 4793.784279707422, 4796.25658023007, 10687.625933092304, 10815.405152716676, 10816.0, 10819.891801082045, 10834.67495975122, 5317.41508434072, 5332.733363331896, 5335.306410166653, 5336.439673834269, 5338.706371149547, 4831.613470922388, 4908.042931628298, 4915.361003069578, 4921.680090794087, 4941.579965702956, 4140.130572579732, 4144.79276036197, 4203.243416185116, 4218.06052604152, 4240.575942405735, 1221.926684398939, 1294.1393973008032, 1316.5326309160303, 1360.1838896661923, 1373.5261334839465, 743.5947408662292, 982.4841957972122, 1009.7324209084599, 1014.7057353367588, 1020.2959957629212, 6178.104174894705, 6238.1266622672865, 6275.341089185661, 6344.270408902943, 6518.250401203827, 865.7143142762873, 892.1078921078921, 897.0670502677273, 898.0, 913.3971578757988, 4304.182205380967, 4331.740095594275, 4344.56554344563, 4359.498657654411, 4391.187630288343, 10939.617113400993, 11339.829902551468, 11361.0, 11369.488373023234, 11369.715757106138, 11333.26333788301, 11334.773304533863, 11338.09295256394, 11344.773104537862, 11346.886531134747, 8040.437169398203, 8375.204355586331, 8399.328053755686, 8542.615582298811, 8706.6517339307, 6120.816375508742, 6181.289151747606, 6292.874142517216, 6307.05394190875, 6321.589096708699, 10170.491475426346, 10406.739831504274, 10524.31591946521, 10640.201984851323, 10665.146788257076, 10329.793404132026, 10533.210009249491, 10544.103751181236, 10599.523021464207, 10799.082078023142, 8142.348612110898, 8185.672573097009, 8325.084240733635, 8325.625346859284, 8329.416940814328, 8651.437656552303, 8675.132486751274, 8692.174243446863, 8721.258693011165, 8755.868661969947, 7683.308502234826, 7886.447948643769, 7946.8807967879375, 7980.521168729895, 7992.080910695112, 7410.4442166836625, 7636.579988100708, 7727.690892364242, 7777.805554861174, 7972.92027079745, 7086.645667716697, 7236.059312289369, 7243.058402407653, 7248.492605517563, 7249.565026098451, 5332.30680011596, 5384.326959130109, 5398.190271459155, 5414.269073675161, 5497.340319161726, 5397.217403476396, 5436.456354364532, 5462.317210348707, 5537.8061767837935, 5558.416366281483], "value": 4770.471535487361, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.9526866238725, 344.23064450952126, 347.36605694607346, 351.75201482954526, 353.803638980366, 5198.350206224222, 5364.597655175868, 5399.973000134999, 5431.592630552709, 5450.100733378998, 5186.222066689998, 5287.915977224669, 5337.345422918898, 5338.0, 5340.54605358545, 4262.936055959163, 4264.185637479264, 4264.296391095466, 4267.613025766638, 4284.250256205151, 4164.354525048622, 4236.428082208909, 4268.231718290706, 4269.039466120129, 4285.100128972919, 4149.792510374469, 4172.102997855461, 4212.85255016073, 4228.217779710763, 4234.9200953756745, 4193.119444916556, 4200.222958752625, 4203.47456567929, 4209.0, 4242.91514169718, 3593.94609080864, 3687.797171155585, 3735.1595890924323, 3736.645018723217, 3795.335816232176, 5228.085085110129, 5228.215767634885, 5236.554892834114, 5250.133727934886, 5251.789928402859, 4826.903461930777, 4840.467548569654, 4853.708777473327, 4862.756862156878, 4873.293372461008, 5410.431904649994, 5551.694656793875, 5569.192467092289, 5597.292825688163, 5643.209950606918, 4049.635532802062, 4086.366613174963, 4086.9386959195635, 4101.364288535252, 4103.384492326145, 5817.38917413674, 5855.941440585625, 5860.21639721129, 5861.325947516048, 5875.911861322126, 4085.71400001997, 4140.399642051885, 4176.248275310473, 4193.0, 4211.410402543646, 4242.151569686075, 4249.638780703613, 4249.80875860587, 4252.958025283763, 4253.383259427365, 4827.25177597473, 4832.371791667061, 4833.226683730586, 4837.564619184291, 4839.661223714376, 5411.377691565521, 5648.830535083954, 5657.462541058593, 5660.943390566042, 5704.458076482728, 1382.6958069224754, 1414.4554346576642, 1416.6883285677134, 1468.0, 1468.6989167220695, 980.8724865767547, 989.3915242126185, 998.0817647763929, 1016.1413605503337, 1016.9779371731363, 5417.945820541744, 5425.511703946664, 5448.536874365724, 5463.360991702473, 5483.7806487740045, 5654.180143879197, 5675.943240567542, 5690.943090569124, 5691.857703557444, 5696.3733989261145, 5241.397239317451, 5326.786928522815, 5458.181272809111, 5467.223152475462, 5477.75350109246, 2659.933501662474, 2676.585129304922, 2689.3411114276737, 2713.552263876478, 2715.6062370956497, 5188.325517682677, 5220.347456567929, 5225.268462415265, 5232.253067139706, 5236.764345604457, 4909.3126962225315, 5078.238264260391, 5084.41529224144, 5156.63903526757, 5201.45384734605, 5148.3564554430695, 5199.870003249874, 5202.271681964528, 5210.014339030645, 5223.895522089613, 4976.776045077908, 5036.8519675203, 5037.765747391913, 5040.722760248222, 5072.315237443046, 4782.928256076231, 4998.850034498971, 5033.84898453047, 5091.210862316347, 5092.618053645917, 5252.816151434681, 5257.2913803014935, 5262.658117137837, 5271.89456210866, 5277.815276465305, 3777.658931079427, 3814.332491813977, 3826.4642949987583, 3848.634379733921, 3858.80705964695, 4791.353167322367, 5011.32347133147, 5026.673266237683, 5528.778848846001, 5703.885922281448, 5507.586930980113, 5646.237757902631, 5691.373948865705, 5703.458171473703, 5709.628874123168, 5640.27971468706, 5641.323041235078, 5651.689157096399, 5666.385080252108, 5671.291088613923, 5472.616916815785, 5538.556915446674, 5619.831405057855, 5672.829815105553, 5676.495728631833, 5703.458171473703, 5709.857253568694, 5714.228579141763, 5714.428557144252, 5714.457126572969, 5423.213634022965, 5567.331920169444, 5570.5265052470995, 5571.777128914798, 5596.580256480862], "value": 4190.941466751816, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.2412260107698, 807.7657479330994, 857.5969294431617, 866.7833041739565, 927.3415874728942, 15967.361305547778, 17617.911910440445, 17630.559236019075, 17652.0, 17656.470305890845, 14891.25543722815, 14953.626159346022, 14956.32696528656, 14983.25083745811, 15093.169875656835, 722.5484072454728, 747.9326860582546, 849.5709666618349, 884.8628462588309, 889.74642226965, 876.4259410086404, 878.7627340618029, 879.6041781198454, 880.1814312689196, 881.8104107616861, 759.1687102622619, 914.9725508234764, 921.0283151275402, 922.796984663373, 922.8615707643874, 765.4641750774452, 890.692711014699, 905.3571963905634, 915.6749353979375, 922.9400088994261, 673.7406098652054, 676.5974245324073, 676.7428377216676, 677.478341676906, 677.5325025732229, 15006.324715387724, 15011.24943752819, 15024.924875375662, 15032.849671503254, 15090.924545377313, 13207.933960330232, 13234.66913327165, 13263.27052012139, 13572.524961626394, 13836.446542138303, 12407.937960310232, 12462.252264864046, 12557.937210313981, 12818.551350702774, 13003.479860805557, 4530.818767249306, 4546.727196368229, 4617.330487079388, 4778.761061946889, 4784.808607655689, 78003.0, 83756.99999999939, 84341.0, 85102.0, 85572.57213713953, 11599.130065244972, 11607.187496875138, 11700.414979251003, 11746.119041071954, 11883.346415947033, 20606.690899636516, 20722.79277207239, 20799.0, 20804.0, 20865.061072251785, 8808.339374546935, 8836.204741573161, 8899.599518021705, 8987.820243595092, 9035.186833185046, 4938.308636790852, 4981.701097934136, 4985.127602669519, 5068.923966140437, 5095.363079615048, 3412.0, 3503.4394496880377, 3513.6662017108333, 3516.2088530081037, 3517.9472307915403, 580.5529742098561, 584.260909948915, 586.8151532267408, 596.8149873539217, 607.8176547035872, 683.1528904158807, 691.2983321928247, 720.2221600671262, 727.1819203396179, 737.5654352284862, 9760.853587196198, 9908.20734341251, 10189.439580823126, 10383.169346452265, 10399.376037437778, 690.4096997067535, 693.3551796828897, 696.7456878239406, 698.1900994846055, 721.1706537481833, 4714.57568818808, 4837.7097374157665, 4920.991196804646, 4932.802687892444, 4974.253861920741, 66839.66580167068, 67984.0, 69287.3071269291, 69450.0, 127955.0, 59933.0, 60157.39842601606, 99858.500707496, 103868.44197337046, 105832.3541911443, 91575.0, 91591.54204228937, 92550.0, 93422.53288733512, 93565.0, 10087.798244035224, 10635.680929572125, 10736.624218152327, 10830.729231769268, 10961.780764384508, 54845.1773223394, 55102.44897551133, 55798.44201557933, 55860.44139558553, 57495.1375729356, 69246.26884327932, 69302.30697692958, 75301.0, 83608.0, 84439.15560844314, 14205.218712970885, 14246.35891384911, 14504.202268874891, 14535.0, 14547.927260363631, 15291.847081529042, 15466.845331546541, 15815.051096934221, 16033.118178500294, 16157.192140393168, 13963.720725585634, 14123.576292711236, 14468.204248765998, 14662.486812961495, 15270.465533706267, 14352.712945741236, 14809.185494797459, 14895.106293622417, 14959.700805983603, 16409.09749963716, 14095.07593006931, 14211.147331160164, 14481.782773258621, 14556.708865822837, 14565.635859103608, 11980.460879260629, 12500.249985000566, 12610.936945315214, 12621.621351359474, 13053.477860885458, 11928.880711192776, 12556.0, 12598.307093109966, 12850.293233872228, 12995.935020325216], "value": 7831.749467907156, "unit": "score"}], "name": "dromaeo_css", "value": 7533.103965417775, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 111.0, 111.0, 111.0, 112.0, 111.0, 111.0, 111.0, 110.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 119.0, 119.0, 167.0, 121.0, 122.0, 116.0, 122.0, 161.0, 118.0], "value": 131.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 160.0, 160.0, 160.0, 162.0, 161.0, 160.0, 159.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 111.0, 76.0, 74.0, 75.0, 75.0, 96.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 119.0, 81.0, 81.0, 81.0, 107.0, 82.0, 81.0, 109.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 118.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 145.0, 146.0, 147.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 102.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 51.0, 52.0, 50.0, 50.0, 51.0, 50.0, 51.0, 50.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 58.0, 60.0, 58.0, 59.0, 58.0, 58.0, 59.0, 58.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 73.0, 74.0, 72.0, 75.0, 76.0, 73.0, 79.0, 75.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 120.0, 119.0, 123.0, 128.0, 122.0, 134.0, 116.0, 158.0, 129.0], "value": 127.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 157.0, 156.0, 154.0, 159.0, 158.0, 160.0, 163.0, 156.0, 156.0], "value": 157.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 67.0, 62.0, 62.0, 63.0, 62.0, 62.0, 63.0, 62.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1464.2, "alertThreshold": 2.0}]} 04:05:24 INFO - Return code: 0 04:05:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:05:25 INFO - # TBPL SUCCESS # 04:05:25 INFO - Running post-action listener: _resource_record_post_action 04:05:25 INFO - [mozharness: 2016-10-15 11:05:25.026273Z] Finished run-tests step (success) 04:05:25 INFO - Running post-run listener: _resource_record_post_run 04:05:25 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 362692608; Read time: 16; Write time: 257664 04:05:25 INFO - TinderboxPrint: CPU usage
13.0% 04:05:25 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 04:05:25 INFO - TinderboxPrint: I/O write bytes / time
362,692,608 / 257,664 04:05:25 INFO - TinderboxPrint: CPU idle
8,262.1 (87.0%) 04:05:25 INFO - TinderboxPrint: CPU user
1,190.2 (12.5%) 04:05:25 INFO - TinderboxPrint: Swap in / out
0 / 0 04:05:25 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114708480; Read time: 0; Write time: 95764 04:05:25 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 247025664; Read time: 0; Write time: 161900 04:05:25 INFO - Running post-run listener: _upload_blobber_files 04:05:25 INFO - Blob upload gear active. 04:05:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:05:25 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 04:05:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:05:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:05:26 INFO - (blobuploader) - INFO - Open directory for files ... 04:05:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:05:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:05:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:05:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:05:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:05:26 INFO - (blobuploader) - INFO - Done attempting. 04:05:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:05:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:05:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:05:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:05:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:05:29 INFO - (blobuploader) - INFO - Done attempting. 04:05:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:05:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:05:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:05:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:05:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:05:30 INFO - (blobuploader) - INFO - Done attempting. 04:05:30 INFO - (blobuploader) - INFO - Iteration through files over. 04:05:30 INFO - Return code: 0 04:05:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:05:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:05:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bce9ae5c3b016eede507df8b9eba8dbbd905ec9b6b9e380fad2dc0c1fe3dd02faa16c3ad9e915f783eb7bd91b1d3bc4dd149718e2acbd3f20fbb87adaa96cf29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab653248f8853ea8a38f439d04ff4f8acb9b57bcec29f90601167047a1de3a2f5859d8c319e4a82b8c39ef809dfbc7a8e0f7d2a31de7a53d9b81fd9b391e67ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3fbe0bb4a1a9c8bd9ef3d50511795d4c7496aeb774f8a7d9b40bb20dfe86911379288ccd2bd2a2fd33ae20c9023872113f2b7dbed3e5b5a3ff1afbc648f729dd"} 04:05:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:05:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:05:30 INFO - Contents: 04:05:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bce9ae5c3b016eede507df8b9eba8dbbd905ec9b6b9e380fad2dc0c1fe3dd02faa16c3ad9e915f783eb7bd91b1d3bc4dd149718e2acbd3f20fbb87adaa96cf29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab653248f8853ea8a38f439d04ff4f8acb9b57bcec29f90601167047a1de3a2f5859d8c319e4a82b8c39ef809dfbc7a8e0f7d2a31de7a53d9b81fd9b391e67ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3fbe0bb4a1a9c8bd9ef3d50511795d4c7496aeb774f8a7d9b40bb20dfe86911379288ccd2bd2a2fd33ae20c9023872113f2b7dbed3e5b5a3ff1afbc648f729dd"} 04:05:30 INFO - Running post-run listener: copy_logs_to_upload_dir 04:05:30 INFO - Copying logs to upload dir... 04:05:30 INFO - mkdir: /builds/slave/test/build/upload/logs 04:05:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.522212 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-10-15 04:05:30.198532) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-15 04:05:30.202159) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bce9ae5c3b016eede507df8b9eba8dbbd905ec9b6b9e380fad2dc0c1fe3dd02faa16c3ad9e915f783eb7bd91b1d3bc4dd149718e2acbd3f20fbb87adaa96cf29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab653248f8853ea8a38f439d04ff4f8acb9b57bcec29f90601167047a1de3a2f5859d8c319e4a82b8c39ef809dfbc7a8e0f7d2a31de7a53d9b81fd9b391e67ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3fbe0bb4a1a9c8bd9ef3d50511795d4c7496aeb774f8a7d9b40bb20dfe86911379288ccd2bd2a2fd33ae20c9023872113f2b7dbed3e5b5a3ff1afbc648f729dd"} build_url:https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011304 build_url: 'https://queue.taskcluster.net/v1/task/XLhkg4rfRE24qIc1a15Ayg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bce9ae5c3b016eede507df8b9eba8dbbd905ec9b6b9e380fad2dc0c1fe3dd02faa16c3ad9e915f783eb7bd91b1d3bc4dd149718e2acbd3f20fbb87adaa96cf29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab653248f8853ea8a38f439d04ff4f8acb9b57bcec29f90601167047a1de3a2f5859d8c319e4a82b8c39ef809dfbc7a8e0f7d2a31de7a53d9b81fd9b391e67ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3fbe0bb4a1a9c8bd9ef3d50511795d4c7496aeb774f8a7d9b40bb20dfe86911379288ccd2bd2a2fd33ae20c9023872113f2b7dbed3e5b5a3ff1afbc648f729dd"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-15 04:05:30.228038) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 04:05:30.228379) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1476528296.515998-796650511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004513 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-15 04:05:30.310708) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-15 04:05:30.311126) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-15 04:05:30.311601) ========= ========= Total master_lag: 0.17 =========