builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-024 starttime: 1481821879.24 results: success (0) buildid: 20161215073239 builduid: 4dfc84ad753f4930bb6608acadafa557 revision: 6dbc6e9f62a705d5f523cc750811bd01c8275ec6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 09:11:19.239451) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 09:11:19.239917) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 09:11:19.240228) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 09:11:19.254429) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:11:19.254699) ========= 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=41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-15 09:11:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 101M=0s 2016-12-15 09:11:19 (101 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104485 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:11:19.388255) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:11:19.388547) ========= 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=41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020213 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:11:19.438234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:11:19.438561) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6dbc6e9f62a705d5f523cc750811bd01c8275ec6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 6dbc6e9f62a705d5f523cc750811bd01c8275ec6 --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=41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-15 09:11:19,820 truncating revision to first 12 chars 2016-12-15 09:11:19,820 Setting DEBUG logging. 2016-12-15 09:11:19,820 attempt 1/10 2016-12-15 09:11:19,820 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6dbc6e9f62a7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-15 09:11:20,142 unpacking tar archive at: mozilla-central-6dbc6e9f62a7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.609089 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:11:20.060762) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 09:11:20.061099) ========= script_repo_revision: 6dbc6e9f62a705d5f523cc750811bd01c8275ec6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 09:11:20.061549) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 09:11:20.062252) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 09:11:20.071653) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-15 09:11:20.072020) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:11:20 INFO - MultiFileLogger online at 20161215 09:11:20 in /builds/slave/test 09:11:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:11:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:11:20 INFO - {'append_to_log': False, 09:11:20 INFO - 'base_work_dir': '/builds/slave/test', 09:11:20 INFO - 'blob_upload_branch': 'Firefox', 09:11:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:11:20 INFO - 'branch': 'Firefox', 09:11:20 INFO - 'buildbot_json_path': 'buildprops.json', 09:11:20 INFO - 'config_files': ('talos/linux_config.py',), 09:11:20 INFO - 'default_actions': ('clobber', 09:11:20 INFO - 'read-buildbot-config', 09:11:20 INFO - 'download-and-extract', 09:11:20 INFO - 'populate-webroot', 09:11:20 INFO - 'create-virtualenv', 09:11:20 INFO - 'install', 09:11:20 INFO - 'run-tests'), 09:11:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:11:20 INFO - 'download_minidump_stackwalk': True, 09:11:20 INFO - 'download_symbols': 'ondemand', 09:11:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:11:20 INFO - 'tooltool.py': '/tools/tooltool.py', 09:11:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:11:20 INFO - '/tools/misc-python/virtualenv.py')}, 09:11:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:11:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:11:20 INFO - 'installer_path': 'installer.exe', 09:11:20 INFO - 'log_level': 'info', 09:11:20 INFO - 'log_name': 'talos', 09:11:20 INFO - 'log_to_console': True, 09:11:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:11:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:11:20 INFO - 'opt_config_files': (), 09:11:20 INFO - 'pip_index': False, 09:11:20 INFO - 'sps_profile': False, 09:11:20 INFO - 'sps_profile_interval': 0, 09:11:20 INFO - 'suite': 'dromaeojs', 09:11:20 INFO - 'system_bits': '32', 09:11:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:11:20 INFO - 'title': 'talos-linux64-ix-024', 09:11:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:11:20 INFO - 'use_talos_json': True, 09:11:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:11:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:11:20 INFO - 'work_dir': 'build'} 09:11:20 INFO - [mozharness: 2016-12-15 17:11:20.556295Z] Running clobber step. 09:11:20 INFO - Running pre-action listener: _resource_record_pre_action 09:11:20 INFO - Running main action method: clobber 09:11:20 INFO - rmtree: /builds/slave/test/build 09:11:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:11:21 INFO - Running post-action listener: _resource_record_post_action 09:11:21 INFO - [mozharness: 2016-12-15 17:11:21.021730Z] Finished clobber step (success) 09:11:21 INFO - [mozharness: 2016-12-15 17:11:21.021831Z] Running read-buildbot-config step. 09:11:21 INFO - Running pre-action listener: _resource_record_pre_action 09:11:21 INFO - Running main action method: read_buildbot_config 09:11:21 INFO - Using buildbot properties: 09:11:21 INFO - { 09:11:21 INFO - "project": "", 09:11:21 INFO - "product": "firefox", 09:11:21 INFO - "script_repo_revision": "production", 09:11:21 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:11:21 INFO - "repository": "", 09:11:21 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:11:21 INFO - "buildid": "20161215073239", 09:11:21 INFO - "pgo_build": "True", 09:11:21 INFO - "basedir": "/builds/slave/test", 09:11:21 INFO - "buildnumber": 115, 09:11:21 INFO - "slavename": "talos-linux64-ix-024", 09:11:21 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:11:21 INFO - "platform": "ubuntu64_hw", 09:11:21 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:11:21 INFO - "branch": "mozilla-central", 09:11:21 INFO - "repo_path": "mozilla-central", 09:11:21 INFO - "revision": "6dbc6e9f62a705d5f523cc750811bd01c8275ec6", 09:11:21 INFO - "stage_platform": "linux64-pgo", 09:11:21 INFO - "builduid": "4dfc84ad753f4930bb6608acadafa557", 09:11:21 INFO - "slavebuilddir": "test" 09:11:21 INFO - } 09:11:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:11:21 INFO - Running post-action listener: _resource_record_post_action 09:11:21 INFO - [mozharness: 2016-12-15 17:11:21.024245Z] Finished read-buildbot-config step (success) 09:11:21 INFO - [mozharness: 2016-12-15 17:11:21.024333Z] Running download-and-extract step. 09:11:21 INFO - Running pre-action listener: _resource_record_pre_action 09:11:21 INFO - Running main action method: download_and_extract 09:11:21 INFO - mkdir: /builds/slave/test/build/tests 09:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:21 INFO - trying https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:11:21 INFO - Downloading https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:11:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:11:21 INFO - Downloaded 1361 bytes. 09:11:21 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:11:21 INFO - Using the following test package requirements: 09:11:21 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:11:21 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:21 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:11:21 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:21 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:11:21 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:21 INFO - u'jsshell-linux-x86_64.zip'], 09:11:21 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:21 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:11:21 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:11:21 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:21 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:11:21 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:21 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:11:21 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:21 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:11:21 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:21 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:11:21 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:11:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:11:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:11:21 INFO - Fetch https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:11:22 INFO - Expected file size: 21775056 09:11:22 INFO - Obtained file size: 21775056 09:11:26 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:11:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:11:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:11:26 INFO - Fetch https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:11:27 INFO - Expected file size: 21775056 09:11:27 INFO - Obtained file size: 21775056 09:11:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:11:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:11:31 INFO - Fetch https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:11:32 INFO - Expected file size: 14409734 09:11:32 INFO - Obtained file size: 14409734 09:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:32 INFO - trying https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:11:32 INFO - Downloading https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:11:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:11:34 INFO - Downloaded 59753400 bytes. 09:11:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:11:34 INFO - mkdir: /builds/slave/test/properties 09:11:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:11:34 INFO - Writing to file /builds/slave/test/properties/build_url 09:11:34 INFO - Contents: 09:11:34 INFO - build_url:https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:11:34 INFO - Running post-action listener: _resource_record_post_action 09:11:34 INFO - Running post-action listener: set_extra_try_arguments 09:11:34 INFO - [mozharness: 2016-12-15 17:11:34.839161Z] Finished download-and-extract step (success) 09:11:34 INFO - [mozharness: 2016-12-15 17:11:34.839388Z] Running populate-webroot step. 09:11:34 INFO - Running pre-action listener: _resource_record_pre_action 09:11:34 INFO - Running main action method: populate_webroot 09:11:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:11:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:11:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:11:34 INFO - '--tppagecycles', 09:11:34 INFO - '7'], 09:11:34 INFO - 'tests': ['tsvgm']}}, 09:11:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:11:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:11:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:11:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:11:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:11:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:11:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:11:34 INFO - 'talos_options': ['--disable-e10s'], 09:11:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:11:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:11:34 INFO - 'talos_options': ['--disable-e10s'], 09:11:34 INFO - 'tests': ['damp', 'tps']}, 09:11:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:11:34 INFO - 'tests': ['damp', 'tps']}, 09:11:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:11:34 INFO - 'tests': ['dromaeo_dom']}, 09:11:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:11:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:11:34 INFO - 'tests': ['basic_compositor_video']}, 09:11:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:11:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:11:34 INFO - 'tests': ['a11yr', 09:11:34 INFO - 'ts_paint', 09:11:34 INFO - 'tpaint', 09:11:34 INFO - 'sessionrestore', 09:11:34 INFO - 'sessionrestore_no_auto_restore', 09:11:34 INFO - 'tabpaint']}, 09:11:34 INFO - 'other-e10s': {'tests': ['a11yr', 09:11:34 INFO - 'ts_paint', 09:11:34 INFO - 'tpaint', 09:11:34 INFO - 'sessionrestore', 09:11:34 INFO - 'sessionrestore_no_auto_restore', 09:11:34 INFO - 'tabpaint']}, 09:11:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:11:34 INFO - 'ts_paint', 09:11:34 INFO - 'tpaint', 09:11:34 INFO - 'sessionrestore', 09:11:34 INFO - 'sessionrestore_no_auto_restore', 09:11:34 INFO - 'tabpaint']}, 09:11:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:11:34 INFO - 'ts_paint', 09:11:34 INFO - 'tpaint', 09:11:34 INFO - 'sessionrestore', 09:11:34 INFO - 'sessionrestore_no_auto_restore', 09:11:34 INFO - 'tabpaint']}, 09:11:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:11:34 INFO - 'tests': ['a11yr', 09:11:34 INFO - 'ts_paint', 09:11:34 INFO - 'tpaint', 09:11:34 INFO - 'sessionrestore', 09:11:34 INFO - 'sessionrestore_no_auto_restore', 09:11:34 INFO - 'tabpaint']}, 09:11:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:11:34 INFO - 'tests': ['a11yr', 09:11:34 INFO - 'ts_paint', 09:11:34 INFO - 'tpaint', 09:11:34 INFO - 'sessionrestore', 09:11:34 INFO - 'sessionrestore_no_auto_restore', 09:11:34 INFO - 'tabpaint']}, 09:11:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:11:34 INFO - 'tests': ['tsvgx', 09:11:34 INFO - 'tsvgr_opacity', 09:11:34 INFO - 'tart', 09:11:34 INFO - 'tscrollx', 09:11:34 INFO - 'cart', 09:11:34 INFO - 'tsvg_static']}, 09:11:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:11:34 INFO - 'tsvgr_opacity', 09:11:34 INFO - 'tart', 09:11:34 INFO - 'tscrollx', 09:11:34 INFO - 'cart', 09:11:34 INFO - 'tsvg_static']}, 09:11:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:11:34 INFO - 'talos_options': ['--disable-e10s'], 09:11:34 INFO - 'tests': ['tp5o']}, 09:11:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:11:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:11:34 INFO - 'talos_options': ['--disable-e10s', 09:11:34 INFO - '--xperf_path', 09:11:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:34 INFO - 'tests': ['tp5n']}, 09:11:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:11:34 INFO - 'talos_options': ['--xperf_path', 09:11:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:34 INFO - 'tests': ['tp5n']}}, 09:11:34 INFO - 'talos.zip': {'path': '', 09:11:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:11:34 INFO - Running post-action listener: _resource_record_post_action 09:11:34 INFO - [mozharness: 2016-12-15 17:11:34.856374Z] Finished populate-webroot step (success) 09:11:34 INFO - [mozharness: 2016-12-15 17:11:34.856461Z] Running create-virtualenv step. 09:11:34 INFO - Running pre-action listener: _resource_record_pre_action 09:11:34 INFO - Running main action method: create_virtualenv 09:11:34 INFO - Creating virtualenv /builds/slave/test/build/venv 09:11:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:11:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:11:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:11:34 INFO - Using real prefix '/usr' 09:11:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:11:35 INFO - Installing distribute.............................................................................................................................................................................................done. 09:11:37 INFO - Installing pip.................done. 09:11:37 INFO - Return code: 0 09:11:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:11:37 INFO - Reading from file tmpfile_stdout 09:11:37 INFO - Output received: 09:11:37 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:11:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:37 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 0x7fe004a3ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1288120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318f20>, '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 0x1285c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1285f90>, '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': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:11:37 INFO - Using env: {'DISPLAY': ':0', 09:11:37 INFO - 'HOME': '/home/cltbld', 09:11:37 INFO - 'LANG': 'en_US.UTF-8', 09:11:37 INFO - 'LANGUAGE': 'en_US:en', 09:11:37 INFO - 'LOGNAME': 'cltbld', 09:11:37 INFO - 'MAIL': '/var/mail/cltbld', 09:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:37 INFO - 'MOZ_NO_REMOTE': '1', 09:11:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:37 INFO - 'NO_EM_RESTART': '1', 09:11:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:37 INFO - 'PWD': '/builds/slave/test', 09:11:37 INFO - 'SHELL': '/bin/bash', 09:11:37 INFO - 'SHLVL': '1', 09:11:37 INFO - 'TERM': 'linux', 09:11:37 INFO - 'TMOUT': '86400', 09:11:37 INFO - 'USER': 'cltbld', 09:11:37 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 09:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:37 INFO - '_': '/tools/buildbot/bin/python'} 09:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:11:37 INFO - Cleaning up... 09:11:37 INFO - Return code: 0 09:11:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:37 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 0x7fe004a3ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1288120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318f20>, '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 0x1285c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1285f90>, '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': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:11:37 INFO - Using env: {'DISPLAY': ':0', 09:11:37 INFO - 'HOME': '/home/cltbld', 09:11:37 INFO - 'LANG': 'en_US.UTF-8', 09:11:37 INFO - 'LANGUAGE': 'en_US:en', 09:11:37 INFO - 'LOGNAME': 'cltbld', 09:11:37 INFO - 'MAIL': '/var/mail/cltbld', 09:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:37 INFO - 'MOZ_NO_REMOTE': '1', 09:11:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:37 INFO - 'NO_EM_RESTART': '1', 09:11:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:37 INFO - 'PWD': '/builds/slave/test', 09:11:37 INFO - 'SHELL': '/bin/bash', 09:11:37 INFO - 'SHLVL': '1', 09:11:37 INFO - 'TERM': 'linux', 09:11:37 INFO - 'TMOUT': '86400', 09:11:37 INFO - 'USER': 'cltbld', 09:11:37 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 09:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:37 INFO - '_': '/tools/buildbot/bin/python'} 09:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:38 INFO - Downloading/unpacking psutil>=3.1.1 09:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:11:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:40 INFO - Installing collected packages: psutil 09:11:40 INFO - Running setup.py install for psutil 09:11:40 INFO - building 'psutil._psutil_linux' extension 09:11:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:11:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:11:40 INFO - building 'psutil._psutil_posix' extension 09:11:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:11:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:11:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:40 INFO - Successfully installed psutil 09:11:40 INFO - Cleaning up... 09:11:40 INFO - Return code: 0 09:11:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe004a3ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1288120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318f20>, '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 0x1285c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1285f90>, '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': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:11:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:11:40 INFO - Using env: {'DISPLAY': ':0', 09:11:40 INFO - 'HOME': '/home/cltbld', 09:11:40 INFO - 'LANG': 'en_US.UTF-8', 09:11:40 INFO - 'LANGUAGE': 'en_US:en', 09:11:40 INFO - 'LOGNAME': 'cltbld', 09:11:40 INFO - 'MAIL': '/var/mail/cltbld', 09:11:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:40 INFO - 'MOZ_NO_REMOTE': '1', 09:11:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:40 INFO - 'NO_EM_RESTART': '1', 09:11:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:40 INFO - 'PWD': '/builds/slave/test', 09:11:40 INFO - 'SHELL': '/bin/bash', 09:11:40 INFO - 'SHLVL': '1', 09:11:40 INFO - 'TERM': 'linux', 09:11:40 INFO - 'TMOUT': '86400', 09:11:40 INFO - 'USER': 'cltbld', 09:11:40 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 09:11:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:40 INFO - '_': '/tools/buildbot/bin/python'} 09:11:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:11:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:11:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:11:43 INFO - Installing collected packages: mozsystemmonitor 09:11:43 INFO - Running setup.py install for mozsystemmonitor 09:11:43 INFO - Successfully installed mozsystemmonitor 09:11:43 INFO - Cleaning up... 09:11:43 INFO - Return code: 0 09:11:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:43 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 0x7fe004a3ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1288120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318f20>, '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 0x1285c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1285f90>, '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': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:11:43 INFO - Using env: {'DISPLAY': ':0', 09:11:43 INFO - 'HOME': '/home/cltbld', 09:11:43 INFO - 'LANG': 'en_US.UTF-8', 09:11:43 INFO - 'LANGUAGE': 'en_US:en', 09:11:43 INFO - 'LOGNAME': 'cltbld', 09:11:43 INFO - 'MAIL': '/var/mail/cltbld', 09:11:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:43 INFO - 'MOZ_NO_REMOTE': '1', 09:11:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:43 INFO - 'NO_EM_RESTART': '1', 09:11:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:43 INFO - 'PWD': '/builds/slave/test', 09:11:43 INFO - 'SHELL': '/bin/bash', 09:11:43 INFO - 'SHLVL': '1', 09:11:43 INFO - 'TERM': 'linux', 09:11:43 INFO - 'TMOUT': '86400', 09:11:43 INFO - 'USER': 'cltbld', 09:11:43 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 09:11:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:43 INFO - '_': '/tools/buildbot/bin/python'} 09:11:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:43 INFO - Downloading/unpacking jsonschema==2.5.1 09:11:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:11:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:45 INFO - Downloading functools32-3.2.3-2.tar.gz 09:11:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:11:45 INFO - warning: no files found matching '*.txt' 09:11:45 INFO - no previously-included directories found matching 'build' 09:11:45 INFO - no previously-included directories found matching 'dist' 09:11:45 INFO - no previously-included directories found matching '.git*' 09:11:45 INFO - Installing collected packages: jsonschema, functools32 09:11:45 INFO - Running setup.py install for functools32 09:11:45 INFO - warning: no files found matching '*.txt' 09:11:45 INFO - no previously-included directories found matching 'build' 09:11:45 INFO - no previously-included directories found matching 'dist' 09:11:45 INFO - no previously-included directories found matching '.git*' 09:11:45 INFO - Successfully installed jsonschema functools32 09:11:45 INFO - Cleaning up... 09:11:45 INFO - Return code: 0 09:11:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:11:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:45 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 0x7fe004a3ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1288120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318f20>, '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 0x1285c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1285f90>, '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': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:11:45 INFO - Using env: {'DISPLAY': ':0', 09:11:45 INFO - 'HOME': '/home/cltbld', 09:11:45 INFO - 'LANG': 'en_US.UTF-8', 09:11:45 INFO - 'LANGUAGE': 'en_US:en', 09:11:45 INFO - 'LOGNAME': 'cltbld', 09:11:45 INFO - 'MAIL': '/var/mail/cltbld', 09:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:45 INFO - 'MOZ_NO_REMOTE': '1', 09:11:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:45 INFO - 'NO_EM_RESTART': '1', 09:11:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:45 INFO - 'PWD': '/builds/slave/test', 09:11:45 INFO - 'SHELL': '/bin/bash', 09:11:45 INFO - 'SHLVL': '1', 09:11:45 INFO - 'TERM': 'linux', 09:11:45 INFO - 'TMOUT': '86400', 09:11:45 INFO - 'USER': 'cltbld', 09:11:45 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 09:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:45 INFO - '_': '/tools/buildbot/bin/python'} 09:11:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:11:46 INFO - Cleaning up... 09:11:46 INFO - Return code: 0 09:11:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:46 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 0x7fe004a3ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1288120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318f20>, '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 0x1285c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1285f90>, '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': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:11:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:11:46 INFO - Using env: {'DISPLAY': ':0', 09:11:46 INFO - 'HOME': '/home/cltbld', 09:11:46 INFO - 'LANG': 'en_US.UTF-8', 09:11:46 INFO - 'LANGUAGE': 'en_US:en', 09:11:46 INFO - 'LOGNAME': 'cltbld', 09:11:46 INFO - 'MAIL': '/var/mail/cltbld', 09:11:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:46 INFO - 'MOZ_NO_REMOTE': '1', 09:11:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:46 INFO - 'NO_EM_RESTART': '1', 09:11:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:46 INFO - 'PWD': '/builds/slave/test', 09:11:46 INFO - 'SHELL': '/bin/bash', 09:11:46 INFO - 'SHLVL': '1', 09:11:46 INFO - 'TERM': 'linux', 09:11:46 INFO - 'TMOUT': '86400', 09:11:46 INFO - 'USER': 'cltbld', 09:11:46 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 09:11:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:46 INFO - '_': '/tools/buildbot/bin/python'} 09:11:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:46 INFO - Downloading/unpacking blobuploader==1.2.4 09:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:48 INFO - Downloading blobuploader-1.2.4.tar.gz 09:11:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:11:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:11:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:11:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:11:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:48 INFO - Downloading docopt-0.6.1.tar.gz 09:11:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:11:49 INFO - Installing collected packages: blobuploader, requests, docopt 09:11:49 INFO - Running setup.py install for blobuploader 09:11:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:11:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:11:49 INFO - Running setup.py install for requests 09:11:49 INFO - Running setup.py install for docopt 09:11:49 INFO - Successfully installed blobuploader requests docopt 09:11:49 INFO - Cleaning up... 09:11:49 INFO - Return code: 0 09:11:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:49 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 0x7fe004a3ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1288120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318f20>, '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 0x1285c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1285f90>, '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': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:11:49 INFO - Using env: {'DISPLAY': ':0', 09:11:49 INFO - 'HOME': '/home/cltbld', 09:11:49 INFO - 'LANG': 'en_US.UTF-8', 09:11:49 INFO - 'LANGUAGE': 'en_US:en', 09:11:49 INFO - 'LOGNAME': 'cltbld', 09:11:49 INFO - 'MAIL': '/var/mail/cltbld', 09:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:49 INFO - 'MOZ_NO_REMOTE': '1', 09:11:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:49 INFO - 'NO_EM_RESTART': '1', 09:11:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:49 INFO - 'PWD': '/builds/slave/test', 09:11:49 INFO - 'SHELL': '/bin/bash', 09:11:49 INFO - 'SHLVL': '1', 09:11:49 INFO - 'TERM': 'linux', 09:11:49 INFO - 'TMOUT': '86400', 09:11:49 INFO - 'USER': 'cltbld', 09:11:49 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 09:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:49 INFO - '_': '/tools/buildbot/bin/python'} 09:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:49 INFO - Running setup.py (path:/tmp/pip-0NegiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:49 INFO - Running setup.py (path:/tmp/pip-YTVm8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:49 INFO - Running setup.py (path:/tmp/pip-3X9Ile-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:50 INFO - Running setup.py (path:/tmp/pip-6AyP4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:50 INFO - Running setup.py (path:/tmp/pip-HG8hEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:50 INFO - Running setup.py (path:/tmp/pip-TArCSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:50 INFO - Running setup.py (path:/tmp/pip-yZwyeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:50 INFO - Running setup.py (path:/tmp/pip-KfmqnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:50 INFO - Running setup.py (path:/tmp/pip-A4Spz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:50 INFO - Running setup.py (path:/tmp/pip-Io5aFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:50 INFO - Running setup.py (path:/tmp/pip-WlvbJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:50 INFO - Running setup.py (path:/tmp/pip-8G1MGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:50 INFO - Running setup.py (path:/tmp/pip-DeRyYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:50 INFO - Running setup.py (path:/tmp/pip-qeLzCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:50 INFO - Running setup.py (path:/tmp/pip-K61JMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:50 INFO - Running setup.py (path:/tmp/pip-KYAFQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:51 INFO - Running setup.py (path:/tmp/pip-OB7_G0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:11:51 INFO - Running setup.py install for manifestparser 09:11:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:11:51 INFO - Running setup.py install for mozcrash 09:11:51 INFO - Running setup.py install for mozdebug 09:11:51 INFO - Running setup.py install for mozdevice 09:11:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:11:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:11:51 INFO - Running setup.py install for mozfile 09:11:51 INFO - Running setup.py install for mozhttpd 09:11:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:11:51 INFO - Running setup.py install for mozinfo 09:11:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:11:51 INFO - Running setup.py install for mozInstall 09:11:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:11:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:11:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:11:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:11:51 INFO - Running setup.py install for mozleak 09:11:51 INFO - Running setup.py install for mozlog 09:11:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:11:52 INFO - Running setup.py install for moznetwork 09:11:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:11:52 INFO - Running setup.py install for mozprocess 09:11:52 INFO - Running setup.py install for mozprofile 09:11:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:11:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:11:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:11:52 INFO - Running setup.py install for mozrunner 09:11:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:11:52 INFO - Running setup.py install for mozscreenshot 09:11:52 INFO - Running setup.py install for moztest 09:11:52 INFO - Running setup.py install for mozversion 09:11:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:11:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:11:52 INFO - Cleaning up... 09:11:52 INFO - Return code: 0 09:11:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:52 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 0x7fe004a3ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1288120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318f20>, '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 0x1285c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1285f90>, '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': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:11:52 INFO - Using env: {'DISPLAY': ':0', 09:11:52 INFO - 'HOME': '/home/cltbld', 09:11:52 INFO - 'LANG': 'en_US.UTF-8', 09:11:52 INFO - 'LANGUAGE': 'en_US:en', 09:11:52 INFO - 'LOGNAME': 'cltbld', 09:11:52 INFO - 'MAIL': '/var/mail/cltbld', 09:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:52 INFO - 'MOZ_NO_REMOTE': '1', 09:11:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:52 INFO - 'NO_EM_RESTART': '1', 09:11:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:52 INFO - 'PWD': '/builds/slave/test', 09:11:52 INFO - 'SHELL': '/bin/bash', 09:11:52 INFO - 'SHLVL': '1', 09:11:52 INFO - 'TERM': 'linux', 09:11:52 INFO - 'TMOUT': '86400', 09:11:52 INFO - 'USER': 'cltbld', 09:11:52 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 09:11:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:52 INFO - '_': '/tools/buildbot/bin/python'} 09:11:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:53 INFO - Running setup.py (path:/tmp/pip-Jb52QX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:53 INFO - Running setup.py (path:/tmp/pip-Q7IDn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:53 INFO - Running setup.py (path:/tmp/pip-i8_qHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:53 INFO - Running setup.py (path:/tmp/pip-dHBGpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:53 INFO - Running setup.py (path:/tmp/pip-P7oXQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:53 INFO - Running setup.py (path:/tmp/pip-Xx_txW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:53 INFO - Running setup.py (path:/tmp/pip-EvwH2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:53 INFO - Running setup.py (path:/tmp/pip-e2_1k8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:53 INFO - Running setup.py (path:/tmp/pip-mS3rXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:53 INFO - Running setup.py (path:/tmp/pip-XScV39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:53 INFO - Running setup.py (path:/tmp/pip-KGo2xx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:53 INFO - Running setup.py (path:/tmp/pip-tuULIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:53 INFO - Running setup.py (path:/tmp/pip-QqyLRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:54 INFO - Running setup.py (path:/tmp/pip-KCVVmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:54 INFO - Running setup.py (path:/tmp/pip-CxRtHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:54 INFO - Running setup.py (path:/tmp/pip-TA1cF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:54 INFO - Running setup.py (path:/tmp/pip-2PQzMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:11:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:56 INFO - Downloading blessings-1.6.tar.gz 09:11:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:11:56 INFO - Installing collected packages: blessings 09:11:56 INFO - Running setup.py install for blessings 09:11:56 INFO - Successfully installed blessings 09:11:56 INFO - Cleaning up... 09:11:56 INFO - Return code: 0 09:11:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:11:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:56 INFO - Reading from file tmpfile_stdout 09:11:56 INFO - Current package versions: 09:11:56 INFO - argparse == 1.2.1 09:11:56 INFO - blessings == 1.6 09:11:56 INFO - blobuploader == 1.2.4 09:11:56 INFO - docopt == 0.6.1 09:11:56 INFO - functools32 == 3.2.3-2 09:11:56 INFO - jsonschema == 2.5.1 09:11:56 INFO - manifestparser == 1.1 09:11:56 INFO - mozInstall == 1.12 09:11:56 INFO - mozcrash == 1.0 09:11:56 INFO - mozdebug == 0.1 09:11:56 INFO - mozdevice == 0.49 09:11:56 INFO - mozfile == 1.2 09:11:56 INFO - mozhttpd == 0.7 09:11:56 INFO - mozinfo == 0.9 09:11:56 INFO - mozleak == 0.1 09:11:56 INFO - mozlog == 3.4 09:11:56 INFO - moznetwork == 0.27 09:11:56 INFO - mozprocess == 0.23 09:11:56 INFO - mozprofile == 0.28 09:11:56 INFO - mozrunner == 6.13 09:11:56 INFO - mozscreenshot == 0.1 09:11:56 INFO - mozsystemmonitor == 0.3 09:11:56 INFO - moztest == 0.7 09:11:56 INFO - mozversion == 1.4 09:11:56 INFO - psutil == 3.1.1 09:11:56 INFO - requests == 1.2.3 09:11:56 INFO - wsgiref == 0.1.2 09:11:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:56 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 0x7fe004a3ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1288120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318f20>, '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 0x1285c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1285f90>, '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': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:11:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:11:56 INFO - Using env: {'DISPLAY': ':0', 09:11:56 INFO - 'HOME': '/home/cltbld', 09:11:56 INFO - 'LANG': 'en_US.UTF-8', 09:11:56 INFO - 'LANGUAGE': 'en_US:en', 09:11:56 INFO - 'LOGNAME': 'cltbld', 09:11:56 INFO - 'MAIL': '/var/mail/cltbld', 09:11:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:56 INFO - 'MOZ_NO_REMOTE': '1', 09:11:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:56 INFO - 'NO_EM_RESTART': '1', 09:11:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:56 INFO - 'PWD': '/builds/slave/test', 09:11:56 INFO - 'SHELL': '/bin/bash', 09:11:56 INFO - 'SHLVL': '1', 09:11:56 INFO - 'TERM': 'linux', 09:11:56 INFO - 'TMOUT': '86400', 09:11:56 INFO - 'USER': 'cltbld', 09:11:56 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 09:11:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:56 INFO - '_': '/tools/buildbot/bin/python'} 09:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:11:57 INFO - Cleaning up... 09:11:57 INFO - Return code: 0 09:11:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe004a3ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1288120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1318f20>, '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 0x1285c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1285f90>, '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': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:11:57 INFO - Using env: {'DISPLAY': ':0', 09:11:57 INFO - 'HOME': '/home/cltbld', 09:11:57 INFO - 'LANG': 'en_US.UTF-8', 09:11:57 INFO - 'LANGUAGE': 'en_US:en', 09:11:57 INFO - 'LOGNAME': 'cltbld', 09:11:57 INFO - 'MAIL': '/var/mail/cltbld', 09:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:57 INFO - 'MOZ_NO_REMOTE': '1', 09:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:57 INFO - 'NO_EM_RESTART': '1', 09:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:57 INFO - 'PWD': '/builds/slave/test', 09:11:57 INFO - 'SHELL': '/bin/bash', 09:11:57 INFO - 'SHLVL': '1', 09:11:57 INFO - 'TERM': 'linux', 09:11:57 INFO - 'TMOUT': '86400', 09:11:57 INFO - 'USER': 'cltbld', 09:11:57 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 09:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:57 INFO - '_': '/tools/buildbot/bin/python'} 09:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:11:57 INFO - Cleaning up... 09:11:57 INFO - Return code: 0 09:11:57 INFO - Running post-action listener: _resource_record_post_action 09:11:57 INFO - Running post-action listener: _start_resource_monitoring 09:11:57 INFO - Starting resource monitoring. 09:11:57 INFO - [mozharness: 2016-12-15 17:11:57.289662Z] Finished create-virtualenv step (success) 09:11:57 INFO - [mozharness: 2016-12-15 17:11:57.290181Z] Running install step. 09:11:57 INFO - Running pre-action listener: _resource_record_pre_action 09:11:57 INFO - Running main action method: install 09:11:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:57 INFO - Reading from file tmpfile_stdout 09:11:57 INFO - Detecting whether we're running mozinstall >=1.0... 09:11:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:11:57 INFO - Reading from file tmpfile_stdout 09:11:57 INFO - Output received: 09:11:57 INFO - Usage: mozinstall [options] installer 09:11:57 INFO - Options: 09:11:57 INFO - -h, --help show this help message and exit 09:11:57 INFO - -d DEST, --destination=DEST 09:11:57 INFO - Directory to install application into. [default: 09:11:57 INFO - "/builds/slave/test"] 09:11:57 INFO - --app=APP Application being installed. [default: firefox] 09:11:57 INFO - mkdir: /builds/slave/test/build/application 09:11:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:12:14 INFO - Reading from file tmpfile_stdout 09:12:14 INFO - Output received: 09:12:14 INFO - /builds/slave/test/build/application/firefox/firefox 09:12:14 INFO - Running post-action listener: _resource_record_post_action 09:12:14 INFO - [mozharness: 2016-12-15 17:12:14.614855Z] Finished install step (success) 09:12:14 INFO - [mozharness: 2016-12-15 17:12:14.615097Z] Running run-tests step. 09:12:14 INFO - Running pre-action listener: _resource_record_pre_action 09:12:14 INFO - Running main action method: run_tests 09:12:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:12:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:12:14 INFO - Python 2.7.3 09:12:14 INFO - Return code: 0 09:12:14 INFO - grabbing minidump binary from tooltool 09:12:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:14 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 0x1318f20>, '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 0x1285c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1285f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:12:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:12:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:12:14 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:12:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:12:14 INFO - Return code: 0 09:12:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:12:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:12:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:12:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:12:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:12:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:12:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:12:14 INFO - Using env: {'DISPLAY': ':0', 09:12:14 INFO - 'HOME': '/home/cltbld', 09:12:14 INFO - 'LANG': 'en_US.UTF-8', 09:12:14 INFO - 'LANGUAGE': 'en_US:en', 09:12:14 INFO - 'LOGNAME': 'cltbld', 09:12:14 INFO - 'MAIL': '/var/mail/cltbld', 09:12:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:12:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:12:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:14 INFO - 'MOZ_NO_REMOTE': '1', 09:12:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:12:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:14 INFO - 'NO_EM_RESTART': '1', 09:12:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:14 INFO - 'PWD': '/builds/slave/test', 09:12:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:12:14 INFO - 'SHELL': '/bin/bash', 09:12:14 INFO - 'SHLVL': '1', 09:12:14 INFO - 'TERM': 'linux', 09:12:14 INFO - 'TMOUT': '86400', 09:12:14 INFO - 'USER': 'cltbld', 09:12:14 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318', 09:12:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:14 INFO - '_': '/tools/buildbot/bin/python'} 09:12:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:12:14 INFO - mozversion application_buildid: 20161215073239 09:12:14 INFO - mozversion application_changeset: 6dbc6e9f62a705d5f523cc750811bd01c8275ec6 09:12:14 INFO - mozversion application_display_name: Nightly 09:12:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:12:14 INFO - mozversion application_name: Firefox 09:12:14 INFO - mozversion application_remotingname: firefox 09:12:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:12:14 INFO - mozversion application_vendor: Mozilla 09:12:14 INFO - mozversion application_version: 53.0a1 09:12:14 INFO - mozversion platform_buildid: 20161215073239 09:12:14 INFO - mozversion platform_changeset: 6dbc6e9f62a705d5f523cc750811bd01c8275ec6 09:12:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:12:14 INFO - mozversion platform_version: 53.0a1 09:12:14 INFO - using testdate: 1481821934 09:12:14 INFO - actual date: 1481821934 09:12:14 INFO - starting webserver on 'localhost:44992' 09:12:14 INFO - SUITE-START | Running 2 tests 09:12:14 INFO - TEST-START | dromaeo_css 09:12:14 INFO - operating with platform_type : linux_ 09:12:14 INFO - Initialising browser for dromaeo_css test... 09:12:14 INFO - TEST-INFO | started process 16153 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxMpT6C/profile http://localhost:44992/getInfo.html) 09:12:18 INFO - PROCESS | 16153 | __metrics Screen width/height:1600/1200 09:12:18 INFO - PROCESS | 16153 | colorDepth:24 09:12:18 INFO - PROCESS | 16153 | Browser inner width/height: 1024/697 09:12:18 INFO - PROCESS | 16153 | __metrics 09:12:19 INFO - PROCESS | 16153 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:12:19 INFO - PROCESS | 16153 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:12:20 INFO - PROCESS | 16153 | console.error: PushService: 09:12:20 INFO - PROCESS | 16153 | stateChangeProcessEnqueue: Error transitioning state 09:12:20 INFO - PROCESS | 16153 | UnknownError 09:12:20 INFO - TEST-INFO | 16153: exit 0 09:12:20 INFO - Browser initialized. 09:12:20 INFO - Running cycle 1/1 for dromaeo_css test... 09:12:20 INFO - TEST-INFO | started process 16242 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxMpT6C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:33 INFO - PROCESS | 16242 | RSS: Main: 172298240 09:12:33 INFO - PROCESS | 16242 | 09:13:23 INFO - PROCESS | 16242 | 1481822003979 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:15:34 INFO - PROCESS | 16242 | Cycle 1(1): loaded http://localhost:44992/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44992/tests/dromaeo/cssquery-ext.html) 09:15:36 INFO - PROCESS | 16242 | RSS: Main: 318779392 09:15:36 INFO - PROCESS | 16242 | 09:18:38 INFO - PROCESS | 16242 | Cycle 1(1): loaded http://localhost:44992/tests/dromaeo/cssquery-ext.html (next: http://localhost:44992/tests/dromaeo/cssquery-jquery.html) 09:18:39 INFO - PROCESS | 16242 | RSS: Main: 275304448 09:18:39 INFO - PROCESS | 16242 | 09:21:41 INFO - PROCESS | 16242 | Cycle 1(1): loaded http://localhost:44992/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44992/tests/dromaeo/cssquery-mootools.html) 09:21:42 INFO - PROCESS | 16242 | RSS: Main: 222064640 09:21:42 INFO - PROCESS | 16242 | 09:24:44 INFO - PROCESS | 16242 | Cycle 1(1): loaded http://localhost:44992/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44992/tests/dromaeo/cssquery-prototype.html) 09:24:44 INFO - PROCESS | 16242 | RSS: Main: 219475968 09:24:44 INFO - PROCESS | 16242 | 09:27:47 INFO - PROCESS | 16242 | Cycle 1(1): loaded http://localhost:44992/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44992/tests/dromaeo/cssquery-yui.html) 09:27:48 INFO - PROCESS | 16242 | RSS: Main: 222629888 09:27:48 INFO - PROCESS | 16242 | 09:30:51 INFO - PROCESS | 16242 | Cycle 1(1): loaded http://localhost:44992/tests/dromaeo/cssquery-yui.html (next: http://localhost:44992/tests/dromaeo/cssquery-dojo.html) 09:30:51 INFO - PROCESS | 16242 | RSS: Main: 315277312 09:30:51 INFO - PROCESS | 16242 | 09:30:51 INFO - PROCESS | 16242 | __start_tp_report 09:30:51 INFO - PROCESS | 16242 | _x_x_mozilla_page_load 09:30:51 INFO - PROCESS | 16242 | _x_x_mozilla_page_load_details 09:30:51 INFO - PROCESS | 16242 | |i|pagename|runs| 09:30:51 INFO - PROCESS | 16242 | |0;dojo.html;2999.7300242978135;3067.171863596829;3076.5538996845444;3107.6892310768912;3118.656947735749;5177.689338639684;5239.266502689627;5339.5728341732665;5415.593830462716;5420.810271640494;5250.081235783732;5260.789568417259;5324.76038578264;5337.866553336168;5378.677279363241;4768.523147685221;4804;4810.278458231276;4841.201201801716;4877.902441951159;4870.147724148278;4899.142650036247;4956.107900577895;4968.428630707461;5009;4705;4860.125177468054;4872.293517439952;4895.461499235082;4967.677100988424;4737.431508219001;4787.593054590329;4789.592884604813;4789.928151077736;4798.928016079762;4739.715617062987;4757.619390448755;4760.119377915097;4761.547652972962;4763.54746299101;5147.150720131174;5210.713410762407;5215.426303106655;5217.5565076968505;5217.921731173998;5570.303712035996;5572.442755724395;5614.129809879475;5643.6049476536655;5668.858278543069;5737.340205876336;5737.913931291034;5746.080627099644;5750.6262092963825;5785.681787501686;5281.471852814688;5309.123994540896;5317.282166907496;5324.281222035053;5328.067588172094;5623.746931388097;5659.971700141473;5667.291588551431;5703.230063941398;5857.589968702235;5519.3652729936575;5519.917201241985;5525.419830917776;5549.5837812163445;5577.274954256002;5634.830955071355;5651;5666.518345940642;5668.829935101953;5673.120666296731;5407.729613519308;5453.400125986138;5576.637518561281;5619.325680918234;5623.100303951348;5558.916616250759;5583.469570390806;5592.412796656374;5618.634788738719;5635;4351.934720979188;4351.9564804352185;4352.086061926968;4352.586504282088;4389.473263208435;3821.9808900955873;3834.3481608126685;3866.2460820140363;3881.6312450317173;3895.824687889052;5307.097793375136;5322.38792538863;5360.812371566976;5369.060414427538;5448.700321482356;5723.227364305849;5754.712264386764;5778.94221057784;5783.392743761929;5783.94216057834;5308.150695888639;5340.759665815048;5353.464653534693;5354.036273470812;5390.51485366319;4818.759062046954;4825.276208568743;4907.484714105039;4934.111859865258;4969.726665033386;5888.057910734176;5889.764409423661;5890.381509941394;5893.528517718572;5934.050551911673;5672.404397538364;5673.602847800615;5676.403977582459;5678;5679.091345384719;5628.268325117709;5663.065594177079;5775.162601422854;5815.970920145542;5863.882722345444;5463.672179669233;5533.280673512418;5576.832695019156;5621.718914054362;5629.493345598916;5572.275604171594;5614.157876318422;5625.493705566519;5631.324241091095;5632.633878797865;5361.410244873139;5406.810761623325;5425.996190704732;5492.176173573836;5550.833474995757;5398.2982212312145;5533.723313834373;5552.111662134039;5557.555395568425;5571.08077167279;5481.780728770805;5502.422245664307;5506.5870059744875;5508.889822203613;5512.2558454608115;5431.185322201702;5479.4520547944885;5541.806036788693;5566.125834594192;5624.718764061862;5351.973240133774;5418.295621569328;5421.05131601976;5454.536364409069;5505.834824955257 09:30:51 INFO - PROCESS | 16242 | |1;ext.html;537.5457738211211;565.7143142712927;566.1507738392411;572.0903763016803;588.6938791828247;31447.52828707571;40119.19761604766;41559.16881662365;41577.376339354894;44605.7769711151;42784.78607606966;43002.7849860751;43072.56927430725;43183;43377.34933975993;8505.574721263927;8668;8682.6092825823;8683;8684.478931264113;7922.088959769614;8118.431709780318;10105.292629515938;10329.63846265381;10339.431331276777;10392;10477.73805654861;10480.213983951196;10482.58069677212;10482.737931551696;10342.06921377076;10388.636397726043;10391.740206494822;10405.219608529353;10411.791764164751;10231.488425578766;10303.072723454925;10311.48442577868;10314.587416503406;10317.329373590692;39563.60436395657;39879.20241595181;39897;39911.401328980086;39976;3189.3621275744713;3332.0836769888233;3428.5542879425516;3440.896773096811;3490.354284457358;26801.597976030374;28185.718142818514;28221.29446763827;28366.14901552936;29375.11874643764;4077.571854955246;4121.960478730831;4127.504699436057;4201.45381100455;4208.558101399371;311166;313595;314403;314994;316227;49725.50274497281;49890;49929.50070499321;49950.25074623884;49953;52702;52761;52876.735616321675;52929;53079.203811942854;14313.928430357782;14758.778618320734;14763.114213147246;14797.334119964627;14866.925665371604;10204.693859184237;10441.373517588969;11376.54493820238;11422.200445968869;11432.485538150802;16511.256993435083;16669.08320042385;16794.748078778826;16919.492415227563;17036.97778133316;6375.649339286384;6382.521310901701;6440.774572889926;6465.4827613790785;6662.535481549664;908.5321059654151;943.061653654618;983.6571536665684;993.5826952679892;1045.1847558904067;31062.844685776425;31239;31403;31459;31623.84188079045;1034.8913364096811;1053.3416614615865;1068.2468859454066;1090.8145615245428;1099.3458891959272;15326;15909.52271431859;16291.59271018234;16336.264868080965;16632.417865374653;505113;507716;508188;508384;508461;49748.50251497439;50215.24677129846;50304;50306.49693503018;50328.748356258715;1183.8918951239186;1223.9449224784905;1278.09255428643;1304.9869501304865;1314.5596225264453;25420.872895635403;27620.309492262855;28626.856865715537;28655.713442866137;29141.854290729258;47299;47806;48049.039019220116;48211.5178848207;48287.5171248283;411249;412981;414668;414917;415577;25227.117050903864;25536;25830.87084564565;26659.86670066637;36255.81872090622;40951.7952410236;41093.383599246634;41130.79434602808;41188.1762364757;41201.38197927093;34412;34904;34982.47526287057;35061.29877402387;35194.82402587971;34903.476447852794;35264.11839704028;35289.29421411506;35397.64602353943;35650.28699426049;33987;34169.31661366808;34296.48555271623;34777.30445391129;35022.82488587541;30014.549781752856;32152.356952860344;32393;32520.349593007533;32675.183120422178;22716.4320891979;22779.77220227843;22840.428989275402;23099.19152829643;23163.884180579662 09:30:51 INFO - PROCESS | 16242 | |2;jquery.html;363.21907898019236;377.41500673955363;377.8545260074871;384.4137690022716;404.8360414032318;14311.92844035781;17734.11329433528;18433.354832580844;19231.32690355838;19518.31685890994;18237.632177586667;18264.726029109574;18475.90762046188;18798.34205802797;18839.43481695548;5457.290552228224;5757.193992840985;5757.971210143944;5758.510526605243;5758.884822303551;4133.070059236663;4326.891827704301;4351.390805287262;5598.916016259759;5704.2299289595785;6238.719257633395;6240.563160578761;6368.9681551592175;6370.426661600455;6378;5505.449455054502;5717.857053573653;5767.221425107598;6352.809415717536;6356.396142366468;5665.093585026376;5671.886562268773;5673.6595804251465;5675.971620141914;5676.035074037382;13274.601761947157;13482.258475783829;13623.863761362358;13632.454701811916;13634.659133521642;2256.571251462217;2257.7629348918454;2269.375921621551;2347.049444974788;2845.516262235425;10119.645812396604;10169.440680762555;10210;10343.224258180591;10474.895251047468;1871;1905.0665174064748;1921.7789954155312;2021.6158929803437;2022.1102714805586;74592.62703686446;77256;77421.61289193574;77819;83318;8794.340424468062;8966.820663586692;9305.441673499612;9379.953100234456;9792.461414622265;12669.809952850715;12743.171693839871;12755.553555625509;12755.93622031884;12768.297743624189;7237.276272372826;7270.8182295441975;7456.925430745623;7621.580813055335;7691.5385076894445;6606.735730570724;6923.342282483156;7184.856302873914;7445.85108297831;7545.660445279976;3866.980665096713;3950.762954222756;3964.940525892114;4114.506259248909;4120.320147175742;1792.7221280701513;1796.5508622844288;1833.679106156444;1852.9629407412046;1985.5036240939764;913.6254135804288;955.3694561589317;959.9616015359308;987.3878195518824;989.1789814453986;17270.22283997223;17783.911080444515;18555.25778968853;19369.12838922224;19893.60212795765;952.3714348529937;959.3140904253353;965.3242730088966;1025.876894772617;1036.9533370998172;10344.5344959477;10683.985021422952;10825.13398928084;10835.891641083488;10898.945505272422;297112;304724;305494;413034;414077;78283.60858195786;81847;84679.57660211659;86700.5664971671;88641;984.3798407003542;998.7453199434243;1030.1346868630237;1032.509557959963;1033.0547548992588;16032.919835400748;17003.659926801643;17339.913300433418;17591.912040439714;17599.384021559184;38503.1496850324;38681.6131838678;39144;39438;40786;53066;54078.45921540734;55007.44992550023;55396.72301638466;55527.72236138793;7318.926810731825;8300.584970751559;8406.495610263404;8520.062793092631;8568.52873091986;12018.879811201776;12707.36463176819;12893.742125157256;13011.804822927854;13210.471581136648;10989.450527473755;11165.106791456501;11201.327920324806;11394.544218231178;11658.77582853822;10812.783744325227;11109.83335249988;11125.888741112485;11334.319940803578;11398.259113157617;10705.678322790549;11633.418329083343;11676.591319303783;11716;11752.88247117552;8169.2647661710735;8541.316694664536;8558.144185581392;8675.219230269306;8847.55762211874;10477.476126193813;11376.20366574365;11447.713807154887;11477.368744718786;11775.705607359885 09:30:51 INFO - PROCESS | 16242 | |3;mootools.html;1306.3337697774132;1408.8379836318823;1426.8929830262732;1537.907725536468;1714.862810975122;7206.855862882746;8041;8282.130376310475;8298.087210406859;8480.957595212016;8274;8353;8353.457025293366;8360.874586881202;8365.372597055215;1960.147335908884;2023.1806118522022;2181.3455963211054;2228.710267665201;2272.6477396003647;2246.180144247346;2259.1302348595827;2261.1068627891973;2267.5804976079394;2268.2514770125817;2196.901139448729;2198.120751699325;2198.824094072478;2245.4049676835652;2247.3482690019955;2149.5593403352354;2161.6865554494666;2171.9999999999923;2188.026328283914;2189.310367234316;2156.9029393677324;2183.497795507042;2185.2242453928943;2185.63936950403;2185.7704940981125;7985.361171106298;8138.837223255561;8251.33989280856;8252.092269850311;8258.42191046627;4910.116179087763;5045.167547354682;5048.293238946514;5052.621053421009;5054.671446355975;2957.319816442209;3046.8324242166673;3272.2637406583567;3276.475763877792;3276.6068071831533;1074.425182527352;1085.6200329884557;1091.612477570459;1111.9777604447866;1122.119136477861;11392.202545821796;11420.42897855104;11465.828012579734;11467.19729618927;11472.827907581393;4238.3430568261965;4389.561043895649;4459.509453960061;4472.195004899149;4493.640508759291;9700.6119755209;9703.320767546204;9707.320487565943;9711.66009189689;9727.805443891224;5012.198048312325;5090.516400941905;5104.642675012788;5105.2086926526445;5117.616178786606;4009.3785463253244;4102.774347410861;4147.896302592399;4566.178087944202;4669.95330046702;3624.4382120771324;3682.834272457746;3741.5510138782975;3752.9437058444146;3795.772253664789;1233.4387853526623;1237.7833879071127;1243.055277988735;1243.2851110611434;1248.731522722619;825.2943733108226;945.1399226703667;947.7867479816986;967.8886928003229;976.9023097690174;5454.072807622713;5777.162311464898;5892.322382926019;5985.917252614907;6062.696865156725;895.1853813030111;935.5789894547481;964.5081008685621;970.5098925042809;971.7036303927229;3650.525431693916;3944.6055394460323;4008.6793056555407;4011.7592944423427;4107.815148318333;10026.749331266778;10275.537600807982;10359.067683908484;10366.637167699246;10386.844197337046;10201.846972295421;10322;10325.32885362449;10336.121429678411;10340.586376544854;7328.8273876179555;7585.051868516435;7768.883466748003;7893.1712170222445;7906.486078404885;5681.346645135697;5688.11834165705;5688.943110568841;5736;5870.55970802183;9168.083191680778;9255.814883702153;9312.394694344846;9337.439753614804;9352.345335826427;9481.525923703924;9562.856557151787;9579.712608621752;9607.087326703951;9620.326577139813;6403.807885763435;6624.403803657694;6625.4037136657935;7159.57042577447;7303.744368947274;7482.363999059923;7663.386929045551;7907;7957.363410927228;8100.635471403683;7060.7881763547175;7140.428765698835;7155.606441645759;7319.817004574928;7351.77944661661;6876.449884009367;6903.723851045902;6917.491485472796;7143.321384468467;7158.141023077264;6668.66656667155;6693.899591506034;6730.653869226272;6734.259231484435;6736.225334086446;4949.28235405857;4956.256561515802;5016.673916195436;5098.643094983316;5174.249733788504;4881.536254055859;4901.068796928572;5055.292259083805;5127.307813445137;5159.432462429055 09:30:51 INFO - PROCESS | 16242 | |4;prototype.html;370.6534390345026;370.8664880642968;375.2026942746663;377.32835552716176;385.23530791379125;5141.614378921587;5171.405288391836;5183.134071734182;5212.817551385698;5224.373075230973;5018.623603229763;5176.50823171799;5215.852512447265;5239.947600523994;5250.816221432251;3947.6447119759214;4024.8188831502653;4047;4174.97912510437;4183.769892655902;4102.482081629786;4108.28105081611;4118.238125946709;4128.401381799637;4160.583941605845;4085.305498065321;4101.876943691679;4113.814878330468;4120.629143377096;4122.9381559276635;3888.6694630956404;4109.4041364002205;4124.505059392877;4145.150244199932;4155.625993660555;3591.078772856509;3594.19130695594;3597.8560857565667;3602.0994751312173;3625.347437461256;5063.341765570489;5101.948980510185;5106.540411362995;5107;5110.744462776846;4691.507391723854;4692.6245900327895;4700.788464519071;4704;4711.7879695413785;5313.734313284321;5446.264754258164;5450.863728406782;5479.863003424946;5497.890042199174;3875.360565506688;3909.1595307008847;3918.941215881764;3930.7051971102283;3958.3270843956598;5694.202811606337;5721.227634269404;5723.771049158028;5730.083186690152;5745.942540574624;3871.470769046211;4051.9797401013398;4060.025593857512;4062.431259623655;4076.6331030207425;4103.097318589906;4103.261412945639;4103.528094269197;4125.257453658311;4147.605977432139;4633.907321853611;4662.7202367858035;4727.64542659302;4738.715677059387;4746.762661866893;5117.2068329409;5244.527992480619;5382.650127741685;5447.319085114361;5539.861503462445;1352.7835546312542;1357.8574249703836;1359.7688392973218;1359.8708122728326;1365.6585853536617;900.5137225898072;940.6049459227141;946.3044662173297;951.9671156794817;953.8982475241107;5143.5113664201845;5158.200478925773;5169.81905633301;5177.559907407837;5193.143131383355;5373.301470808769;5382.811601594004;5419.729013549307;5425.593080518976;5466.644668096561;5037.924431133536;5135.050015747024;5140.537351638371;5153.4073581538605;5322.520973112439;2912.62135922333;2934.8972785952387;2938.2213713365977;2942.367391010943;2947.8673459694364;5724.198612194296;5751.482366587027;5751.482366587027;5756.625819321731;5804.9419505805245;5434.375046869582;5538.670719027405;5575.275214222206;5590.440955904458;5600.467955544217;5624.21261023457;5637.716724726411;5645.294338207724;5652.293463317085;5653.434656534314;5301.25782390458;5504.779808807602;5515.751791169488;5520.641158324696;5526.751296191601;5401.054815407367;5409.8360655738015;5415.945840541544;5426.213199086032;5441.5102640762525;5691.26013618227;5692.829215123553;5705.885882282414;5726.856828579319;5734.283214598176;3792.563855156692;3862.227554489057;3876.786776727194;3905.6875449964505;3911.6675082618294;5346.732663366894;5349.679019258857;5439.292891924025;5474.452554744494;5491.478309560585;5356.973215133899;5487;5495.752691128829;5511.338639363142;5511.889762204813;5458.4541545845095;5460.863478413072;5462.945370546244;5469.3983661797965;5486.807961721321;5141.637466071569;5369.570434365163;5370.677759334452;5388.622796404371;5405.324334458192;5466.808661696821;5470.863228419322;5479.287692599936;5486.972565137149;5490.945090549203;5092.516210959953;5339.492748188916;5358.919616205682;5381.67709937405;5389.380221274603 09:30:51 INFO - PROCESS | 16242 | |5;yui.html;685.2051620120659;699.8705239530688;725.3326939215921;744.3970383988969;810.1291112054543;14178.574642760708;15145.091294522337;15334.463293784705;15406.537803865875;15620.140892250922;14111.858881411208;14363.425462981495;14410.423583056665;14415.351309191085;14469.493567725105;772.6638912073238;793.0998316910319;793.131520984521;793.9245771651683;794.4438892775081;669.3473862983576;677.5426587053744;772.6136931534232;785.1598789295456;793.1513280789558;833.4332653795417;841.8358420108082;841.9242268195861;842.2251528593678;842.5113434208175;840.1598401598402;840.2018082821344;840.3193413335157;840.5965136734371;841.3227351981652;515.5025400488547;529.9443558426349;609.2567068176817;609.3875654966774;610.3255902228019;13778.862211377858;14371.640708982359;14406.567802965928;14842.480513182094;14853.554393368217;14637.560873173821;14822.851771482256;15001.174935378549;15006.324715387724;15110.54668359951;11674.41627918609;11739.06087512997;11746.353950532717;11783.646490605295;11841.40792960357;3715.9071023224365;3760.2796869413073;3773.735838491307;3802.0874990002476;3924.038610540436;71498.2850171502;73804.63097684477;75099;75790.62104689442;78555.21444785537;11164.106871450265;11223.214374993671;11287.43562821872;11374.601888934014;11417.942910285561;21702.348929532138;21721;21741.891290543444;21841.890790545945;21918.78081219199;7889.0927543333255;7906.209379062047;7997.880031799527;8060.717874874351;8118.797030074177;3957.881263562098;3977.3039718049226;4078.7756673382655;4097.44684467599;4251.06476575153;3362.3779600773446;3459.9827000864834;3464.7921124732597;3465.925563075404;3551.786892786441;589.2310534752104;590.5334785519477;595.0657467775637;611.911272865432;615.3139249736512;700.4081551089322;705.4901863230388;715.1918332284589;718.313507482024;726.6984201556295;9328.533573321307;9546.856797148048;9713.854292185622;9716.31985761004;9910.504474776377;529.9099153143975;560.3360018378235;602.2050892821434;615.4860691322706;660.8579155481594;4735.905281894412;4925.630577706686;4957.677750946177;4966.751662416865;4986.700797952134;78310;79784.60107699424;81125.59437202776;82554;84335.57832210799;60210.698946504985;65732.34267657262;70061;70095;73949.89075163877;61016.694916525135;63222;63623;64476.35523644798;64801.35198647953;9287.257019438564;9301.767455813388;9469.47917864524;9543.331966762538;9678.70963871085;43637.34543981779;43714.34428483639;44995.10009799851;45031.77484112558;45803;54411.72794136004;59547.70226149015;60251.39748602458;61379.386206139156;63508.68245658742;11583.6524904253;11879.76240475203;11952.64142075739;12060.819087713868;12880.871191288343;13677.957620526893;14075.436982520585;14144.858551414354;14213.147211167363;14512.854871451573;13209;13669.521566745116;13959;13976.231307278198;14337.351204611603;13098.410571524113;13266.696955929292;13368.465261389825;13566.38951247216;13756.17462952226;13473.730525389634;13554.864451355756;13708.314584270945;13775.173489590657;14236.145831250158;10460.424676642857;10527.420991845522;10538.736531586772;10708;11406.201565890307;11248;11429.257098288585;11502.309861408343;11540.711482213012;11590.362530060927 09:30:51 INFO - PROCESS | 16242 | __end_tp_report 09:30:51 INFO - PROCESS | 16242 | __start_cc_report 09:30:51 INFO - PROCESS | 16242 | _x_x_mozilla_cycle_collect,1326 09:30:51 INFO - PROCESS | 16242 | __end_cc_report 09:30:51 INFO - PROCESS | 16242 | __startTimestamp1481823051463__endTimestamp 09:30:51 INFO - PROCESS | 16242 | 09:30:51 INFO - PROCESS | 16242 | ------- Summary: start ------- 09:30:51 INFO - PROCESS | 16242 | Number of tests: 6 09:30:51 INFO - PROCESS | 16242 | 09:30:51 INFO - PROCESS | 16242 | [#0] dojo.html Cycles:165 Average:5251.40 Median:5431.19 stddev:586.45 (10.8%) stddev-sans-first:561.02 09:30:51 INFO - PROCESS | 16242 | Values: 2999.7 3067.2 3076.6 3107.7 3118.7 5177.7 5239.3 5339.6 5415.6 5420.8 5250.1 5260.8 5324.8 5337.9 5378.7 4768.5 4804.0 4810.3 4841.2 4877.9 4870.1 4899.1 4956.1 4968.4 5009.0 4705.0 4860.1 4872.3 4895.5 4967.7 4737.4 4787.6 4789.6 4789.9 4798.9 4739.7 4757.6 4760.1 4761.5 4763.5 5147.2 5210.7 5215.4 5217.6 5217.9 5570.3 5572.4 5614.1 5643.6 5668.9 5737.3 5737.9 5746.1 5750.6 5785.7 5281.5 5309.1 5317.3 5324.3 5328.1 5623.7 5660.0 5667.3 5703.2 5857.6 5519.4 5519.9 5525.4 5549.6 5577.3 5634.8 5651.0 5666.5 5668.8 5673.1 5407.7 5453.4 5576.6 5619.3 5623.1 5558.9 5583.5 5592.4 5618.6 5635.0 4351.9 4352.0 4352.1 4352.6 4389.5 3822.0 3834.3 3866.2 3881.6 3895.8 5307.1 5322.4 5360.8 5369.1 5448.7 5723.2 5754.7 5778.9 5783.4 5783.9 5308.2 5340.8 5353.5 5354.0 5390.5 4818.8 4825.3 4907.5 4934.1 4969.7 5888.1 5889.8 5890.4 5893.5 5934.1 5672.4 5673.6 5676.4 5678.0 5679.1 5628.3 5663.1 5775.2 5816.0 5863.9 5463.7 5533.3 5576.8 5621.7 5629.5 5572.3 5614.2 5625.5 5631.3 5632.6 5361.4 5406.8 5426.0 5492.2 5550.8 5398.3 5533.7 5552.1 5557.6 5571.1 5481.8 5502.4 5506.6 5508.9 5512.3 5431.2 5479.5 5541.8 5566.1 5624.7 5352.0 5418.3 5421.1 5454.5 5505.8 09:30:51 INFO - PROCESS | 16242 | 09:30:51 INFO - PROCESS | 16242 | [#1] ext.html Cycles:180 Average:55576.51 Median:18926.45 stddev:111267.17 (587.9%) stddev-sans-first:111502.56 09:30:51 INFO - PROCESS | 16242 | Values: 537.5 565.7 566.2 572.1 588.7 31447.5 40119.2 41559.2 41577.4 44605.8 42784.8 43002.8 43072.6 43183.0 43377.3 8505.6 8668.0 8682.6 8683.0 8684.5 7922.1 8118.4 10105.3 10329.6 10339.4 10392.0 10477.7 10480.2 10482.6 10482.7 10342.1 10388.6 10391.7 10405.2 10411.8 10231.5 10303.1 10311.5 10314.6 10317.3 39563.6 39879.2 39897.0 39911.4 39976.0 3189.4 3332.1 3428.6 3440.9 3490.4 26801.6 28185.7 28221.3 28366.1 29375.1 4077.6 4122.0 4127.5 4201.5 4208.6 311166.0 313595.0 314403.0 314994.0 316227.0 49725.5 49890.0 49929.5 49950.3 49953.0 52702.0 52761.0 52876.7 52929.0 53079.2 14313.9 14758.8 14763.1 14797.3 14866.9 10204.7 10441.4 11376.5 11422.2 11432.5 16511.3 16669.1 16794.7 16919.5 17037.0 6375.6 6382.5 6440.8 6465.5 6662.5 908.5 943.1 983.7 993.6 1045.2 31062.8 31239.0 31403.0 31459.0 31623.8 1034.9 1053.3 1068.2 1090.8 1099.3 15326.0 15909.5 16291.6 16336.3 16632.4 505113.0 507716.0 508188.0 508384.0 508461.0 49748.5 50215.2 50304.0 50306.5 50328.7 1183.9 1223.9 1278.1 1305.0 1314.6 25420.9 27620.3 28626.9 28655.7 29141.9 47299.0 47806.0 48049.0 48211.5 48287.5 411249.0 412981.0 414668.0 414917.0 415577.0 25227.1 25536.0 25830.9 26659.9 36255.8 40951.8 41093.4 41130.8 41188.2 41201.4 34412.0 34904.0 34982.5 35061.3 35194.8 34903.5 35264.1 35289.3 35397.6 35650.3 33987.0 34169.3 34296.5 34777.3 35022.8 30014.5 32152.4 32393.0 32520.3 32675.2 22716.4 22779.8 22840.4 23099.2 23163.9 09:30:51 INFO - PROCESS | 16242 | 09:30:51 INFO - PROCESS | 16242 | [#2] jquery.html Cycles:180 Average:23968.63 Median:381.13 stddev:58711.74 (15404.5%) stddev-sans-first:58849.69 09:30:51 INFO - PROCESS | 16242 | Values: 363.2 377.4 377.9 384.4 404.8 14311.9 17734.1 18433.4 19231.3 19518.3 18237.6 18264.7 18475.9 18798.3 18839.4 5457.3 5757.2 5758.0 5758.5 5758.9 4133.1 4326.9 4351.4 5598.9 5704.2 6238.7 6240.6 6369.0 6370.4 6378.0 5505.4 5717.9 5767.2 6352.8 6356.4 5665.1 5671.9 5673.7 5676.0 5676.0 13274.6 13482.3 13623.9 13632.5 13634.7 2256.6 2257.8 2269.4 2347.0 2845.5 10119.6 10169.4 10210.0 10343.2 10474.9 1871.0 1905.1 1921.8 2021.6 2022.1 74592.6 77256.0 77421.6 77819.0 83318.0 8794.3 8966.8 9305.4 9380.0 9792.5 12669.8 12743.2 12755.6 12755.9 12768.3 7237.3 7270.8 7456.9 7621.6 7691.5 6606.7 6923.3 7184.9 7445.9 7545.7 3867.0 3950.8 3964.9 4114.5 4120.3 1792.7 1796.6 1833.7 1853.0 1985.5 913.6 955.4 960.0 987.4 989.2 17270.2 17783.9 18555.3 19369.1 19893.6 952.4 959.3 965.3 1025.9 1037.0 10344.5 10684.0 10825.1 10835.9 10898.9 297112.0 304724.0 305494.0 413034.0 414077.0 78283.6 81847.0 84679.6 86700.6 88641.0 984.4 998.7 1030.1 1032.5 1033.1 16032.9 17003.7 17339.9 17591.9 17599.4 38503.1 38681.6 39144.0 39438.0 40786.0 53066.0 54078.5 55007.4 55396.7 55527.7 7318.9 8300.6 8406.5 8520.1 8568.5 12018.9 12707.4 12893.7 13011.8 13210.5 10989.5 11165.1 11201.3 11394.5 11658.8 10812.8 11109.8 11125.9 11334.3 11398.3 10705.7 11633.4 11676.6 11716.0 11752.9 8169.3 8541.3 8558.1 8675.2 8847.6 10477.5 11376.2 11447.7 11477.4 11775.7 09:30:51 INFO - PROCESS | 16242 | 09:30:51 INFO - PROCESS | 16242 | [#3] mootools.html Cycles:180 Average:5454.94 Median:5054.98 stddev:3061.86 (60.6%) stddev-sans-first:3054.58 09:30:51 INFO - PROCESS | 16242 | Values: 1306.3 1408.8 1426.9 1537.9 1714.9 7206.9 8041.0 8282.1 8298.1 8481.0 8274.0 8353.0 8353.5 8360.9 8365.4 1960.1 2023.2 2181.3 2228.7 2272.6 2246.2 2259.1 2261.1 2267.6 2268.3 2196.9 2198.1 2198.8 2245.4 2247.3 2149.6 2161.7 2172.0 2188.0 2189.3 2156.9 2183.5 2185.2 2185.6 2185.8 7985.4 8138.8 8251.3 8252.1 8258.4 4910.1 5045.2 5048.3 5052.6 5054.7 2957.3 3046.8 3272.3 3276.5 3276.6 1074.4 1085.6 1091.6 1112.0 1122.1 11392.2 11420.4 11465.8 11467.2 11472.8 4238.3 4389.6 4459.5 4472.2 4493.6 9700.6 9703.3 9707.3 9711.7 9727.8 5012.2 5090.5 5104.6 5105.2 5117.6 4009.4 4102.8 4147.9 4566.2 4670.0 3624.4 3682.8 3741.6 3752.9 3795.8 1233.4 1237.8 1243.1 1243.3 1248.7 825.3 945.1 947.8 967.9 976.9 5454.1 5777.2 5892.3 5985.9 6062.7 895.2 935.6 964.5 970.5 971.7 3650.5 3944.6 4008.7 4011.8 4107.8 10026.7 10275.5 10359.1 10366.6 10386.8 10201.8 10322.0 10325.3 10336.1 10340.6 7328.8 7585.1 7768.9 7893.2 7906.5 5681.3 5688.1 5688.9 5736.0 5870.6 9168.1 9255.8 9312.4 9337.4 9352.3 9481.5 9562.9 9579.7 9607.1 9620.3 6403.8 6624.4 6625.4 7159.6 7303.7 7482.4 7663.4 7907.0 7957.4 8100.6 7060.8 7140.4 7155.6 7319.8 7351.8 6876.4 6903.7 6917.5 7143.3 7158.1 6668.7 6693.9 6730.7 6734.3 6736.2 4949.3 4956.3 5016.7 5098.6 5174.2 4881.5 4901.1 5055.3 5127.3 5159.4 09:30:51 INFO - PROCESS | 16242 | 09:30:51 INFO - PROCESS | 16242 | [#4] prototype.html Cycles:180 Average:4574.60 Median:5188.14 stddev:1326.04 (25.6%) stddev-sans-first:1291.68 09:30:51 INFO - PROCESS | 16242 | Values: 370.7 370.9 375.2 377.3 385.2 5141.6 5171.4 5183.1 5212.8 5224.4 5018.6 5176.5 5215.9 5239.9 5250.8 3947.6 4024.8 4047.0 4175.0 4183.8 4102.5 4108.3 4118.2 4128.4 4160.6 4085.3 4101.9 4113.8 4120.6 4122.9 3888.7 4109.4 4124.5 4145.2 4155.6 3591.1 3594.2 3597.9 3602.1 3625.3 5063.3 5101.9 5106.5 5107.0 5110.7 4691.5 4692.6 4700.8 4704.0 4711.8 5313.7 5446.3 5450.9 5479.9 5497.9 3875.4 3909.2 3918.9 3930.7 3958.3 5694.2 5721.2 5723.8 5730.1 5745.9 3871.5 4052.0 4060.0 4062.4 4076.6 4103.1 4103.3 4103.5 4125.3 4147.6 4633.9 4662.7 4727.6 4738.7 4746.8 5117.2 5244.5 5382.7 5447.3 5539.9 1352.8 1357.9 1359.8 1359.9 1365.7 900.5 940.6 946.3 952.0 953.9 5143.5 5158.2 5169.8 5177.6 5193.1 5373.3 5382.8 5419.7 5425.6 5466.6 5037.9 5135.1 5140.5 5153.4 5322.5 2912.6 2934.9 2938.2 2942.4 2947.9 5724.2 5751.5 5751.5 5756.6 5804.9 5434.4 5538.7 5575.3 5590.4 5600.5 5624.2 5637.7 5645.3 5652.3 5653.4 5301.3 5504.8 5515.8 5520.6 5526.8 5401.1 5409.8 5415.9 5426.2 5441.5 5691.3 5692.8 5705.9 5726.9 5734.3 3792.6 3862.2 3876.8 3905.7 3911.7 5346.7 5349.7 5439.3 5474.5 5491.5 5357.0 5487.0 5495.8 5511.3 5511.9 5458.5 5460.9 5462.9 5469.4 5486.8 5141.6 5369.6 5370.7 5388.6 5405.3 5466.8 5470.9 5479.3 5487.0 5490.9 5092.5 5339.5 5358.9 5381.7 5389.4 09:30:51 INFO - PROCESS | 16242 | 09:30:51 INFO - PROCESS | 16242 | [#5] yui.html Cycles:180 Average:17636.06 Median:4830.77 stddev:22644.94 (468.8%) stddev-sans-first:22672.69 09:30:51 INFO - PROCESS | 16242 | Values: 685.2 699.9 725.3 744.4 810.1 14178.6 15145.1 15334.5 15406.5 15620.1 14111.9 14363.4 14410.4 14415.4 14469.5 772.7 793.1 793.1 793.9 794.4 669.3 677.5 772.6 785.2 793.2 833.4 841.8 841.9 842.2 842.5 840.2 840.2 840.3 840.6 841.3 515.5 529.9 609.3 609.4 610.3 13778.9 14371.6 14406.6 14842.5 14853.6 14637.6 14822.9 15001.2 15006.3 15110.5 11674.4 11739.1 11746.4 11783.6 11841.4 3715.9 3760.3 3773.7 3802.1 3924.0 71498.3 73804.6 75099.0 75790.6 78555.2 11164.1 11223.2 11287.4 11374.6 11417.9 21702.3 21721.0 21741.9 21841.9 21918.8 7889.1 7906.2 7997.9 8060.7 8118.8 3957.9 3977.3 4078.8 4097.4 4251.1 3362.4 3460.0 3464.8 3465.9 3551.8 589.2 590.5 595.1 611.9 615.3 700.4 705.5 715.2 718.3 726.7 9328.5 9546.9 9713.9 9716.3 9910.5 529.9 560.3 602.2 615.5 660.9 4735.9 4925.6 4957.7 4966.8 4986.7 78310.0 79784.6 81125.6 82554.0 84335.6 60210.7 65732.3 70061.0 70095.0 73949.9 61016.7 63222.0 63623.0 64476.4 64801.4 9287.3 9301.8 9469.5 9543.3 9678.7 43637.3 43714.3 44995.1 45031.8 45803.0 54411.7 59547.7 60251.4 61379.4 63508.7 11583.7 11879.8 11952.6 12060.8 12880.9 13678.0 14075.4 14144.9 14213.1 14512.9 13209.0 13669.5 13959.0 13976.2 14337.4 13098.4 13266.7 13368.5 13566.4 13756.2 13473.7 13554.9 13708.3 13775.2 14236.1 10460.4 10527.4 10538.7 10708.0 11406.2 11248.0 11429.3 11502.3 11540.7 11590.4 09:30:51 INFO - PROCESS | 16242 | -------- Summary: end -------- 09:30:51 INFO - PROCESS | 16242 | 09:30:52 INFO - TEST-INFO | 16242: exit 0 09:30:52 INFO - TEST-OK | dromaeo_css | took 1117531ms 09:30:52 INFO - TEST-START | kraken 09:30:52 INFO - operating with platform_type : linux_ 09:30:52 INFO - Initialising browser for kraken test... 09:30:52 INFO - TEST-INFO | started process 16557 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJDPX9K/profile http://localhost:44992/getInfo.html) 09:30:56 INFO - PROCESS | 16557 | __metrics Screen width/height:1600/1200 09:30:56 INFO - PROCESS | 16557 | colorDepth:24 09:30:56 INFO - PROCESS | 16557 | Browser inner width/height: 1024/697 09:30:56 INFO - PROCESS | 16557 | __metrics 09:30:57 INFO - PROCESS | 16557 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:30:57 INFO - PROCESS | 16557 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:30:57 INFO - PROCESS | 16557 | console.error: PushService: 09:30:57 INFO - PROCESS | 16557 | stateChangeProcessEnqueue: Error transitioning state 09:30:57 INFO - PROCESS | 16557 | UnknownError 09:30:58 INFO - TEST-INFO | 16557: exit 0 09:30:58 INFO - Browser initialized. 09:30:58 INFO - Running cycle 1/1 for kraken test... 09:30:58 INFO - TEST-INFO | started process 16639 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJDPX9K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:31:10 INFO - PROCESS | 16639 | RSS: Main: 172425216 09:31:10 INFO - PROCESS | 16639 | 09:31:13 INFO - PROCESS | 16639 | Cycle 1(1): loaded http://localhost:44992/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44992/tests/kraken/driver.html?testName=audio-beat-detection) 09:31:13 INFO - PROCESS | 16639 | RSS: Main: 230187008 09:31:13 INFO - PROCESS | 16639 | 09:31:16 INFO - PROCESS | 16639 | Cycle 1(1): loaded http://localhost:44992/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44992/tests/kraken/driver.html?testName=audio-dft) 09:31:17 INFO - PROCESS | 16639 | RSS: Main: 217096192 09:31:17 INFO - PROCESS | 16639 | 09:31:20 INFO - PROCESS | 16639 | Cycle 1(1): loaded http://localhost:44992/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44992/tests/kraken/driver.html?testName=audio-fft) 09:31:20 INFO - PROCESS | 16639 | RSS: Main: 298225664 09:31:20 INFO - PROCESS | 16639 | 09:31:22 INFO - PROCESS | 16639 | Cycle 1(1): loaded http://localhost:44992/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44992/tests/kraken/driver.html?testName=audio-oscillator) 09:31:23 INFO - PROCESS | 16639 | RSS: Main: 210104320 09:31:23 INFO - PROCESS | 16639 | 09:31:25 INFO - PROCESS | 16639 | Cycle 1(1): loaded http://localhost:44992/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44992/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:31:26 INFO - PROCESS | 16639 | RSS: Main: 213811200 09:31:26 INFO - PROCESS | 16639 | 09:31:29 INFO - PROCESS | 16639 | Cycle 1(1): loaded http://localhost:44992/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44992/tests/kraken/driver.html?testName=imaging-darkroom) 09:31:29 INFO - PROCESS | 16639 | RSS: Main: 252489728 09:31:29 INFO - PROCESS | 16639 | 09:31:33 INFO - PROCESS | 16639 | Cycle 1(1): loaded http://localhost:44992/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44992/tests/kraken/driver.html?testName=imaging-desaturate) 09:31:33 INFO - PROCESS | 16639 | RSS: Main: 253251584 09:31:33 INFO - PROCESS | 16639 | 09:31:36 INFO - PROCESS | 16639 | Cycle 1(1): loaded http://localhost:44992/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44992/tests/kraken/driver.html?testName=json-parse-financial) 09:31:37 INFO - PROCESS | 16639 | RSS: Main: 251600896 09:31:37 INFO - PROCESS | 16639 | 09:31:38 INFO - PROCESS | 16639 | Cycle 1(1): loaded http://localhost:44992/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44992/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:31:39 INFO - PROCESS | 16639 | RSS: Main: 213901312 09:31:39 INFO - PROCESS | 16639 | 09:31:41 INFO - PROCESS | 16639 | Cycle 1(1): loaded http://localhost:44992/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44992/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:31:41 INFO - PROCESS | 16639 | RSS: Main: 219836416 09:31:41 INFO - PROCESS | 16639 | 09:31:43 INFO - PROCESS | 16639 | Cycle 1(1): loaded http://localhost:44992/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44992/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:31:44 INFO - PROCESS | 16639 | RSS: Main: 229257216 09:31:44 INFO - PROCESS | 16639 | 09:31:47 INFO - PROCESS | 16639 | Cycle 1(1): loaded http://localhost:44992/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44992/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:31:47 INFO - PROCESS | 16639 | RSS: Main: 238587904 09:31:47 INFO - PROCESS | 16639 | 09:31:50 INFO - PROCESS | 16639 | Cycle 1(1): loaded http://localhost:44992/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44992/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:31:51 INFO - PROCESS | 16639 | RSS: Main: 235687936 09:31:51 INFO - PROCESS | 16639 | 09:31:52 INFO - PROCESS | 16639 | Cycle 1(1): loaded http://localhost:44992/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44992/tests/kraken/driver.html?testName=ai-astar) 09:31:53 INFO - PROCESS | 16639 | RSS: Main: 287076352 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | __start_tp_report 09:31:53 INFO - PROCESS | 16639 | _x_x_mozilla_page_load 09:31:53 INFO - PROCESS | 16639 | _x_x_mozilla_page_load_details 09:31:53 INFO - PROCESS | 16639 | |i|pagename|runs| 09:31:53 INFO - PROCESS | 16639 | |0;ai-astar;109;113;111;111;113;111;109;111;111;111 09:31:53 INFO - PROCESS | 16639 | |1;audio-beat-detection;146;123;123;161;123;124;125;122;147;124 09:31:53 INFO - PROCESS | 16639 | |2;audio-dft;163;161;162;159;159;162;162;161;159;160 09:31:53 INFO - PROCESS | 16639 | |3;audio-fft;74;75;76;112;77;77;77;77;92;76 09:31:53 INFO - PROCESS | 16639 | |4;audio-oscillator;85;84;105;85;84;84;97;84;84;114 09:31:53 INFO - PROCESS | 16639 | |5;imaging-gaussian-blur;128;128;128;128;127;127;127;127;127;127 09:31:53 INFO - PROCESS | 16639 | |6;imaging-darkroom;147;146;147;147;146;145;146;145;146;146 09:31:53 INFO - PROCESS | 16639 | |7;imaging-desaturate;96;96;95;95;96;96;95;96;96;96 09:31:53 INFO - PROCESS | 16639 | |8;json-parse-financial;52;51;50;51;53;53;51;53;52;54 09:31:53 INFO - PROCESS | 16639 | |9;json-stringify-tinderbox;63;64;63;64;64;64;64;64;64;63 09:31:53 INFO - PROCESS | 16639 | |10;stanford-crypto-aes;82;82;82;82;81;82;91;80;81;81 09:31:53 INFO - PROCESS | 16639 | |11;stanford-crypto-ccm;143;131;133;133;134;143;141;143;174;137 09:31:53 INFO - PROCESS | 16639 | |12;stanford-crypto-pbkdf2;175;172;172;167;169;171;168;170;166;168 09:31:53 INFO - PROCESS | 16639 | |13;stanford-crypto-sha256-iterative;66;69;64;65;66;64;67;64;64;63 09:31:53 INFO - PROCESS | 16639 | __end_tp_report 09:31:53 INFO - PROCESS | 16639 | __start_cc_report 09:31:53 INFO - PROCESS | 16639 | _x_x_mozilla_cycle_collect,1671 09:31:53 INFO - PROCESS | 16639 | __end_cc_report 09:31:53 INFO - PROCESS | 16639 | __startTimestamp1481823113157__endTimestamp 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | ------- Summary: start ------- 09:31:53 INFO - PROCESS | 16639 | Number of tests: 14 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.00 stddev:1.33 (1.2%) stddev-sans-first:1.20 09:31:53 INFO - PROCESS | 16639 | Values: 109.0 113.0 111.0 111.0 113.0 111.0 109.0 111.0 111.0 111.0 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | [#1] audio-beat-detection Cycles:10 Average:131.80 Median:124.50 stddev:14.07 (11.3%) stddev-sans-first:13.95 09:31:53 INFO - PROCESS | 16639 | Values: 146.0 123.0 123.0 161.0 123.0 124.0 125.0 122.0 147.0 124.0 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.50 stddev:1.48 (0.9%) stddev-sans-first:1.33 09:31:53 INFO - PROCESS | 16639 | Values: 163.0 161.0 162.0 159.0 159.0 162.0 162.0 161.0 159.0 160.0 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | [#3] audio-fft Cycles:10 Average:81.30 Median:77.00 stddev:11.93 (15.5%) stddev-sans-first:12.35 09:31:53 INFO - PROCESS | 16639 | Values: 74.0 75.0 76.0 112.0 77.0 77.0 77.0 77.0 92.0 76.0 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:84.00 stddev:10.94 (13.0%) stddev-sans-first:11.41 09:31:53 INFO - PROCESS | 16639 | Values: 85.0 84.0 105.0 85.0 84.0 84.0 97.0 84.0 84.0 114.0 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | [#5] imaging-gaussian-blur Cycles:10 Average:127.40 Median:127.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 09:31:53 INFO - PROCESS | 16639 | Values: 128.0 128.0 128.0 128.0 127.0 127.0 127.0 127.0 127.0 127.0 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 09:31:53 INFO - PROCESS | 16639 | Values: 147.0 146.0 147.0 147.0 146.0 145.0 146.0 145.0 146.0 146.0 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 09:31:53 INFO - PROCESS | 16639 | Values: 96.0 96.0 95.0 95.0 96.0 96.0 95.0 96.0 96.0 96.0 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.50 stddev:1.25 (2.4%) stddev-sans-first:1.32 09:31:53 INFO - PROCESS | 16639 | Values: 52.0 51.0 50.0 51.0 53.0 53.0 51.0 53.0 52.0 54.0 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | [#9] json-stringify-tinderbox Cycles:10 Average:63.70 Median:64.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 09:31:53 INFO - PROCESS | 16639 | Values: 63.0 64.0 63.0 64.0 64.0 64.0 64.0 64.0 64.0 63.0 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:82.00 stddev:3.10 (3.8%) stddev-sans-first:3.28 09:31:53 INFO - PROCESS | 16639 | Values: 82.0 82.0 82.0 82.0 81.0 82.0 91.0 80.0 81.0 81.0 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | [#11] stanford-crypto-ccm Cycles:10 Average:141.20 Median:142.00 stddev:12.44 (8.8%) stddev-sans-first:13.18 09:31:53 INFO - PROCESS | 16639 | Values: 143.0 131.0 133.0 133.0 134.0 143.0 141.0 143.0 174.0 137.0 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:170.50 stddev:2.74 (1.6%) stddev-sans-first:2.17 09:31:53 INFO - PROCESS | 16639 | Values: 175.0 172.0 172.0 167.0 169.0 171.0 168.0 170.0 166.0 168.0 09:31:53 INFO - PROCESS | 16639 | 09:31:53 INFO - PROCESS | 16639 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.50 stddev:1.81 (2.8%) stddev-sans-first:1.90 09:31:53 INFO - PROCESS | 16639 | Values: 66.0 69.0 64.0 65.0 66.0 64.0 67.0 64.0 64.0 63.0 09:31:53 INFO - PROCESS | 16639 | -------- Summary: end -------- 09:31:53 INFO - PROCESS | 16639 | 09:31:54 INFO - TEST-INFO | 16639: exit 0 09:31:54 INFO - TEST-OK | kraken | took 62029ms 09:31:54 INFO - SUITE-END | took 1179s 09:31:54 INFO - Completed test suite (00:19:39) 09:31:54 INFO - javascript benchmark 09:31:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2999.7300242978135, 3067.171863596829, 3076.5538996845444, 3107.6892310768912, 3118.656947735749, 5177.689338639684, 5239.266502689627, 5339.5728341732665, 5415.593830462716, 5420.810271640494, 5250.081235783732, 5260.789568417259, 5324.76038578264, 5337.866553336168, 5378.677279363241, 4768.523147685221, 4804.0, 4810.278458231276, 4841.201201801716, 4877.902441951159, 4870.147724148278, 4899.142650036247, 4956.107900577895, 4968.428630707461, 5009.0, 4705.0, 4860.125177468054, 4872.293517439952, 4895.461499235082, 4967.677100988424, 4737.431508219001, 4787.593054590329, 4789.592884604813, 4789.928151077736, 4798.928016079762, 4739.715617062987, 4757.619390448755, 4760.119377915097, 4761.547652972962, 4763.54746299101, 5147.150720131174, 5210.713410762407, 5215.426303106655, 5217.5565076968505, 5217.921731173998, 5570.303712035996, 5572.442755724395, 5614.129809879475, 5643.6049476536655, 5668.858278543069, 5737.340205876336, 5737.913931291034, 5746.080627099644, 5750.6262092963825, 5785.681787501686, 5281.471852814688, 5309.123994540896, 5317.282166907496, 5324.281222035053, 5328.067588172094, 5623.746931388097, 5659.971700141473, 5667.291588551431, 5703.230063941398, 5857.589968702235, 5519.3652729936575, 5519.917201241985, 5525.419830917776, 5549.5837812163445, 5577.274954256002, 5634.830955071355, 5651.0, 5666.518345940642, 5668.829935101953, 5673.120666296731, 5407.729613519308, 5453.400125986138, 5576.637518561281, 5619.325680918234, 5623.100303951348, 5558.916616250759, 5583.469570390806, 5592.412796656374, 5618.634788738719, 5635.0, 4351.934720979188, 4351.9564804352185, 4352.086061926968, 4352.586504282088, 4389.473263208435, 3821.9808900955873, 3834.3481608126685, 3866.2460820140363, 3881.6312450317173, 3895.824687889052, 5307.097793375136, 5322.38792538863, 5360.812371566976, 5369.060414427538, 5448.700321482356, 5723.227364305849, 5754.712264386764, 5778.94221057784, 5783.392743761929, 5783.94216057834, 5308.150695888639, 5340.759665815048, 5353.464653534693, 5354.036273470812, 5390.51485366319, 4818.759062046954, 4825.276208568743, 4907.484714105039, 4934.111859865258, 4969.726665033386, 5888.057910734176, 5889.764409423661, 5890.381509941394, 5893.528517718572, 5934.050551911673, 5672.404397538364, 5673.602847800615, 5676.403977582459, 5678.0, 5679.091345384719, 5628.268325117709, 5663.065594177079, 5775.162601422854, 5815.970920145542, 5863.882722345444, 5463.672179669233, 5533.280673512418, 5576.832695019156, 5621.718914054362, 5629.493345598916, 5572.275604171594, 5614.157876318422, 5625.493705566519, 5631.324241091095, 5632.633878797865, 5361.410244873139, 5406.810761623325, 5425.996190704732, 5492.176173573836, 5550.833474995757, 5398.2982212312145, 5533.723313834373, 5552.111662134039, 5557.555395568425, 5571.08077167279, 5481.780728770805, 5502.422245664307, 5506.5870059744875, 5508.889822203613, 5512.2558454608115, 5431.185322201702, 5479.4520547944885, 5541.806036788693, 5566.125834594192, 5624.718764061862, 5351.973240133774, 5418.295621569328, 5421.05131601976, 5454.536364409069, 5505.834824955257], "value": 5212.377219933504, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.5457738211211, 565.7143142712927, 566.1507738392411, 572.0903763016803, 588.6938791828247, 31447.52828707571, 40119.19761604766, 41559.16881662365, 41577.376339354894, 44605.7769711151, 42784.78607606966, 43002.7849860751, 43072.56927430725, 43183.0, 43377.34933975993, 8505.574721263927, 8668.0, 8682.6092825823, 8683.0, 8684.478931264113, 7922.088959769614, 8118.431709780318, 10105.292629515938, 10329.63846265381, 10339.431331276777, 10392.0, 10477.73805654861, 10480.213983951196, 10482.58069677212, 10482.737931551696, 10342.06921377076, 10388.636397726043, 10391.740206494822, 10405.219608529353, 10411.791764164751, 10231.488425578766, 10303.072723454925, 10311.48442577868, 10314.587416503406, 10317.329373590692, 39563.60436395657, 39879.20241595181, 39897.0, 39911.401328980086, 39976.0, 3189.3621275744713, 3332.0836769888233, 3428.5542879425516, 3440.896773096811, 3490.354284457358, 26801.597976030374, 28185.718142818514, 28221.29446763827, 28366.14901552936, 29375.11874643764, 4077.571854955246, 4121.960478730831, 4127.504699436057, 4201.45381100455, 4208.558101399371, 311166.0, 313595.0, 314403.0, 314994.0, 316227.0, 49725.50274497281, 49890.0, 49929.50070499321, 49950.25074623884, 49953.0, 52702.0, 52761.0, 52876.735616321675, 52929.0, 53079.203811942854, 14313.928430357782, 14758.778618320734, 14763.114213147246, 14797.334119964627, 14866.925665371604, 10204.693859184237, 10441.373517588969, 11376.54493820238, 11422.200445968869, 11432.485538150802, 16511.256993435083, 16669.08320042385, 16794.748078778826, 16919.492415227563, 17036.97778133316, 6375.649339286384, 6382.521310901701, 6440.774572889926, 6465.4827613790785, 6662.535481549664, 908.5321059654151, 943.061653654618, 983.6571536665684, 993.5826952679892, 1045.1847558904067, 31062.844685776425, 31239.0, 31403.0, 31459.0, 31623.84188079045, 1034.8913364096811, 1053.3416614615865, 1068.2468859454066, 1090.8145615245428, 1099.3458891959272, 15326.0, 15909.52271431859, 16291.59271018234, 16336.264868080965, 16632.417865374653, 505113.0, 507716.0, 508188.0, 508384.0, 508461.0, 49748.50251497439, 50215.24677129846, 50304.0, 50306.49693503018, 50328.748356258715, 1183.8918951239186, 1223.9449224784905, 1278.09255428643, 1304.9869501304865, 1314.5596225264453, 25420.872895635403, 27620.309492262855, 28626.856865715537, 28655.713442866137, 29141.854290729258, 47299.0, 47806.0, 48049.039019220116, 48211.5178848207, 48287.5171248283, 411249.0, 412981.0, 414668.0, 414917.0, 415577.0, 25227.117050903864, 25536.0, 25830.87084564565, 26659.86670066637, 36255.81872090622, 40951.7952410236, 41093.383599246634, 41130.79434602808, 41188.1762364757, 41201.38197927093, 34412.0, 34904.0, 34982.47526287057, 35061.29877402387, 35194.82402587971, 34903.476447852794, 35264.11839704028, 35289.29421411506, 35397.64602353943, 35650.28699426049, 33987.0, 34169.31661366808, 34296.48555271623, 34777.30445391129, 35022.82488587541, 30014.549781752856, 32152.356952860344, 32393.0, 32520.349593007533, 32675.183120422178, 22716.4320891979, 22779.77220227843, 22840.428989275402, 23099.19152829643, 23163.884180579662], "value": 18750.101954933012, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.21907898019236, 377.41500673955363, 377.8545260074871, 384.4137690022716, 404.8360414032318, 14311.92844035781, 17734.11329433528, 18433.354832580844, 19231.32690355838, 19518.31685890994, 18237.632177586667, 18264.726029109574, 18475.90762046188, 18798.34205802797, 18839.43481695548, 5457.290552228224, 5757.193992840985, 5757.971210143944, 5758.510526605243, 5758.884822303551, 4133.070059236663, 4326.891827704301, 4351.390805287262, 5598.916016259759, 5704.2299289595785, 6238.719257633395, 6240.563160578761, 6368.9681551592175, 6370.426661600455, 6378.0, 5505.449455054502, 5717.857053573653, 5767.221425107598, 6352.809415717536, 6356.396142366468, 5665.093585026376, 5671.886562268773, 5673.6595804251465, 5675.971620141914, 5676.035074037382, 13274.601761947157, 13482.258475783829, 13623.863761362358, 13632.454701811916, 13634.659133521642, 2256.571251462217, 2257.7629348918454, 2269.375921621551, 2347.049444974788, 2845.516262235425, 10119.645812396604, 10169.440680762555, 10210.0, 10343.224258180591, 10474.895251047468, 1871.0, 1905.0665174064748, 1921.7789954155312, 2021.6158929803437, 2022.1102714805586, 74592.62703686446, 77256.0, 77421.61289193574, 77819.0, 83318.0, 8794.340424468062, 8966.820663586692, 9305.441673499612, 9379.953100234456, 9792.461414622265, 12669.809952850715, 12743.171693839871, 12755.553555625509, 12755.93622031884, 12768.297743624189, 7237.276272372826, 7270.8182295441975, 7456.925430745623, 7621.580813055335, 7691.5385076894445, 6606.735730570724, 6923.342282483156, 7184.856302873914, 7445.85108297831, 7545.660445279976, 3866.980665096713, 3950.762954222756, 3964.940525892114, 4114.506259248909, 4120.320147175742, 1792.7221280701513, 1796.5508622844288, 1833.679106156444, 1852.9629407412046, 1985.5036240939764, 913.6254135804288, 955.3694561589317, 959.9616015359308, 987.3878195518824, 989.1789814453986, 17270.22283997223, 17783.911080444515, 18555.25778968853, 19369.12838922224, 19893.60212795765, 952.3714348529937, 959.3140904253353, 965.3242730088966, 1025.876894772617, 1036.9533370998172, 10344.5344959477, 10683.985021422952, 10825.13398928084, 10835.891641083488, 10898.945505272422, 297112.0, 304724.0, 305494.0, 413034.0, 414077.0, 78283.60858195786, 81847.0, 84679.57660211659, 86700.5664971671, 88641.0, 984.3798407003542, 998.7453199434243, 1030.1346868630237, 1032.509557959963, 1033.0547548992588, 16032.919835400748, 17003.659926801643, 17339.913300433418, 17591.912040439714, 17599.384021559184, 38503.1496850324, 38681.6131838678, 39144.0, 39438.0, 40786.0, 53066.0, 54078.45921540734, 55007.44992550023, 55396.72301638466, 55527.72236138793, 7318.926810731825, 8300.584970751559, 8406.495610263404, 8520.062793092631, 8568.52873091986, 12018.879811201776, 12707.36463176819, 12893.742125157256, 13011.804822927854, 13210.471581136648, 10989.450527473755, 11165.106791456501, 11201.327920324806, 11394.544218231178, 11658.77582853822, 10812.783744325227, 11109.83335249988, 11125.888741112485, 11334.319940803578, 11398.259113157617, 10705.678322790549, 11633.418329083343, 11676.591319303783, 11716.0, 11752.88247117552, 8169.2647661710735, 8541.316694664536, 8558.144185581392, 8675.219230269306, 8847.55762211874, 10477.476126193813, 11376.20366574365, 11447.713807154887, 11477.368744718786, 11775.705607359885], "value": 8644.654406752406, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1306.3337697774132, 1408.8379836318823, 1426.8929830262732, 1537.907725536468, 1714.862810975122, 7206.855862882746, 8041.0, 8282.130376310475, 8298.087210406859, 8480.957595212016, 8274.0, 8353.0, 8353.457025293366, 8360.874586881202, 8365.372597055215, 1960.147335908884, 2023.1806118522022, 2181.3455963211054, 2228.710267665201, 2272.6477396003647, 2246.180144247346, 2259.1302348595827, 2261.1068627891973, 2267.5804976079394, 2268.2514770125817, 2196.901139448729, 2198.120751699325, 2198.824094072478, 2245.4049676835652, 2247.3482690019955, 2149.5593403352354, 2161.6865554494666, 2171.9999999999923, 2188.026328283914, 2189.310367234316, 2156.9029393677324, 2183.497795507042, 2185.2242453928943, 2185.63936950403, 2185.7704940981125, 7985.361171106298, 8138.837223255561, 8251.33989280856, 8252.092269850311, 8258.42191046627, 4910.116179087763, 5045.167547354682, 5048.293238946514, 5052.621053421009, 5054.671446355975, 2957.319816442209, 3046.8324242166673, 3272.2637406583567, 3276.475763877792, 3276.6068071831533, 1074.425182527352, 1085.6200329884557, 1091.612477570459, 1111.9777604447866, 1122.119136477861, 11392.202545821796, 11420.42897855104, 11465.828012579734, 11467.19729618927, 11472.827907581393, 4238.3430568261965, 4389.561043895649, 4459.509453960061, 4472.195004899149, 4493.640508759291, 9700.6119755209, 9703.320767546204, 9707.320487565943, 9711.66009189689, 9727.805443891224, 5012.198048312325, 5090.516400941905, 5104.642675012788, 5105.2086926526445, 5117.616178786606, 4009.3785463253244, 4102.774347410861, 4147.896302592399, 4566.178087944202, 4669.95330046702, 3624.4382120771324, 3682.834272457746, 3741.5510138782975, 3752.9437058444146, 3795.772253664789, 1233.4387853526623, 1237.7833879071127, 1243.055277988735, 1243.2851110611434, 1248.731522722619, 825.2943733108226, 945.1399226703667, 947.7867479816986, 967.8886928003229, 976.9023097690174, 5454.072807622713, 5777.162311464898, 5892.322382926019, 5985.917252614907, 6062.696865156725, 895.1853813030111, 935.5789894547481, 964.5081008685621, 970.5098925042809, 971.7036303927229, 3650.525431693916, 3944.6055394460323, 4008.6793056555407, 4011.7592944423427, 4107.815148318333, 10026.749331266778, 10275.537600807982, 10359.067683908484, 10366.637167699246, 10386.844197337046, 10201.846972295421, 10322.0, 10325.32885362449, 10336.121429678411, 10340.586376544854, 7328.8273876179555, 7585.051868516435, 7768.883466748003, 7893.1712170222445, 7906.486078404885, 5681.346645135697, 5688.11834165705, 5688.943110568841, 5736.0, 5870.55970802183, 9168.083191680778, 9255.814883702153, 9312.394694344846, 9337.439753614804, 9352.345335826427, 9481.525923703924, 9562.856557151787, 9579.712608621752, 9607.087326703951, 9620.326577139813, 6403.807885763435, 6624.403803657694, 6625.4037136657935, 7159.57042577447, 7303.744368947274, 7482.363999059923, 7663.386929045551, 7907.0, 7957.363410927228, 8100.635471403683, 7060.7881763547175, 7140.428765698835, 7155.606441645759, 7319.817004574928, 7351.77944661661, 6876.449884009367, 6903.723851045902, 6917.491485472796, 7143.321384468467, 7158.141023077264, 6668.66656667155, 6693.899591506034, 6730.653869226272, 6734.259231484435, 6736.225334086446, 4949.28235405857, 4956.256561515802, 5016.673916195436, 5098.643094983316, 5174.249733788504, 4881.536254055859, 4901.068796928572, 5055.292259083805, 5127.307813445137, 5159.432462429055], "value": 4392.921957635117, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.6534390345026, 370.8664880642968, 375.2026942746663, 377.32835552716176, 385.23530791379125, 5141.614378921587, 5171.405288391836, 5183.134071734182, 5212.817551385698, 5224.373075230973, 5018.623603229763, 5176.50823171799, 5215.852512447265, 5239.947600523994, 5250.816221432251, 3947.6447119759214, 4024.8188831502653, 4047.0, 4174.97912510437, 4183.769892655902, 4102.482081629786, 4108.28105081611, 4118.238125946709, 4128.401381799637, 4160.583941605845, 4085.305498065321, 4101.876943691679, 4113.814878330468, 4120.629143377096, 4122.9381559276635, 3888.6694630956404, 4109.4041364002205, 4124.505059392877, 4145.150244199932, 4155.625993660555, 3591.078772856509, 3594.19130695594, 3597.8560857565667, 3602.0994751312173, 3625.347437461256, 5063.341765570489, 5101.948980510185, 5106.540411362995, 5107.0, 5110.744462776846, 4691.507391723854, 4692.6245900327895, 4700.788464519071, 4704.0, 4711.7879695413785, 5313.734313284321, 5446.264754258164, 5450.863728406782, 5479.863003424946, 5497.890042199174, 3875.360565506688, 3909.1595307008847, 3918.941215881764, 3930.7051971102283, 3958.3270843956598, 5694.202811606337, 5721.227634269404, 5723.771049158028, 5730.083186690152, 5745.942540574624, 3871.470769046211, 4051.9797401013398, 4060.025593857512, 4062.431259623655, 4076.6331030207425, 4103.097318589906, 4103.261412945639, 4103.528094269197, 4125.257453658311, 4147.605977432139, 4633.907321853611, 4662.7202367858035, 4727.64542659302, 4738.715677059387, 4746.762661866893, 5117.2068329409, 5244.527992480619, 5382.650127741685, 5447.319085114361, 5539.861503462445, 1352.7835546312542, 1357.8574249703836, 1359.7688392973218, 1359.8708122728326, 1365.6585853536617, 900.5137225898072, 940.6049459227141, 946.3044662173297, 951.9671156794817, 953.8982475241107, 5143.5113664201845, 5158.200478925773, 5169.81905633301, 5177.559907407837, 5193.143131383355, 5373.301470808769, 5382.811601594004, 5419.729013549307, 5425.593080518976, 5466.644668096561, 5037.924431133536, 5135.050015747024, 5140.537351638371, 5153.4073581538605, 5322.520973112439, 2912.62135922333, 2934.8972785952387, 2938.2213713365977, 2942.367391010943, 2947.8673459694364, 5724.198612194296, 5751.482366587027, 5751.482366587027, 5756.625819321731, 5804.9419505805245, 5434.375046869582, 5538.670719027405, 5575.275214222206, 5590.440955904458, 5600.467955544217, 5624.21261023457, 5637.716724726411, 5645.294338207724, 5652.293463317085, 5653.434656534314, 5301.25782390458, 5504.779808807602, 5515.751791169488, 5520.641158324696, 5526.751296191601, 5401.054815407367, 5409.8360655738015, 5415.945840541544, 5426.213199086032, 5441.5102640762525, 5691.26013618227, 5692.829215123553, 5705.885882282414, 5726.856828579319, 5734.283214598176, 3792.563855156692, 3862.227554489057, 3876.786776727194, 3905.6875449964505, 3911.6675082618294, 5346.732663366894, 5349.679019258857, 5439.292891924025, 5474.452554744494, 5491.478309560585, 5356.973215133899, 5487.0, 5495.752691128829, 5511.338639363142, 5511.889762204813, 5458.4541545845095, 5460.863478413072, 5462.945370546244, 5469.3983661797965, 5486.807961721321, 5141.637466071569, 5369.570434365163, 5370.677759334452, 5388.622796404371, 5405.324334458192, 5466.808661696821, 5470.863228419322, 5479.287692599936, 5486.972565137149, 5490.945090549203, 5092.516210959953, 5339.492748188916, 5358.919616205682, 5381.67709937405, 5389.380221274603], "value": 4164.407154568216, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.2051620120659, 699.8705239530688, 725.3326939215921, 744.3970383988969, 810.1291112054543, 14178.574642760708, 15145.091294522337, 15334.463293784705, 15406.537803865875, 15620.140892250922, 14111.858881411208, 14363.425462981495, 14410.423583056665, 14415.351309191085, 14469.493567725105, 772.6638912073238, 793.0998316910319, 793.131520984521, 793.9245771651683, 794.4438892775081, 669.3473862983576, 677.5426587053744, 772.6136931534232, 785.1598789295456, 793.1513280789558, 833.4332653795417, 841.8358420108082, 841.9242268195861, 842.2251528593678, 842.5113434208175, 840.1598401598402, 840.2018082821344, 840.3193413335157, 840.5965136734371, 841.3227351981652, 515.5025400488547, 529.9443558426349, 609.2567068176817, 609.3875654966774, 610.3255902228019, 13778.862211377858, 14371.640708982359, 14406.567802965928, 14842.480513182094, 14853.554393368217, 14637.560873173821, 14822.851771482256, 15001.174935378549, 15006.324715387724, 15110.54668359951, 11674.41627918609, 11739.06087512997, 11746.353950532717, 11783.646490605295, 11841.40792960357, 3715.9071023224365, 3760.2796869413073, 3773.735838491307, 3802.0874990002476, 3924.038610540436, 71498.2850171502, 73804.63097684477, 75099.0, 75790.62104689442, 78555.21444785537, 11164.106871450265, 11223.214374993671, 11287.43562821872, 11374.601888934014, 11417.942910285561, 21702.348929532138, 21721.0, 21741.891290543444, 21841.890790545945, 21918.78081219199, 7889.0927543333255, 7906.209379062047, 7997.880031799527, 8060.717874874351, 8118.797030074177, 3957.881263562098, 3977.3039718049226, 4078.7756673382655, 4097.44684467599, 4251.06476575153, 3362.3779600773446, 3459.9827000864834, 3464.7921124732597, 3465.925563075404, 3551.786892786441, 589.2310534752104, 590.5334785519477, 595.0657467775637, 611.911272865432, 615.3139249736512, 700.4081551089322, 705.4901863230388, 715.1918332284589, 718.313507482024, 726.6984201556295, 9328.533573321307, 9546.856797148048, 9713.854292185622, 9716.31985761004, 9910.504474776377, 529.9099153143975, 560.3360018378235, 602.2050892821434, 615.4860691322706, 660.8579155481594, 4735.905281894412, 4925.630577706686, 4957.677750946177, 4966.751662416865, 4986.700797952134, 78310.0, 79784.60107699424, 81125.59437202776, 82554.0, 84335.57832210799, 60210.698946504985, 65732.34267657262, 70061.0, 70095.0, 73949.89075163877, 61016.694916525135, 63222.0, 63623.0, 64476.35523644798, 64801.35198647953, 9287.257019438564, 9301.767455813388, 9469.47917864524, 9543.331966762538, 9678.70963871085, 43637.34543981779, 43714.34428483639, 44995.10009799851, 45031.77484112558, 45803.0, 54411.72794136004, 59547.70226149015, 60251.39748602458, 61379.386206139156, 63508.68245658742, 11583.6524904253, 11879.76240475203, 11952.64142075739, 12060.819087713868, 12880.871191288343, 13677.957620526893, 14075.436982520585, 14144.858551414354, 14213.147211167363, 14512.854871451573, 13209.0, 13669.521566745116, 13959.0, 13976.231307278198, 14337.351204611603, 13098.410571524113, 13266.696955929292, 13368.465261389825, 13566.38951247216, 13756.17462952226, 13473.730525389634, 13554.864451355756, 13708.314584270945, 13775.173489590657, 14236.145831250158, 10460.424676642857, 10527.420991845522, 10538.736531586772, 10708.0, 11406.201565890307, 11248.0, 11429.257098288585, 11502.309861408343, 11540.711482213012, 11590.362530060927], "value": 7096.696607875308, "unit": "score"}], "name": "dromaeo_css", "value": 6918.826001205187, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 113.0, 111.0, 111.0, 113.0, 111.0, 109.0, 111.0, 111.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 123.0, 123.0, 161.0, 123.0, 124.0, 125.0, 122.0, 147.0, 124.0], "value": 131.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 159.0, 159.0, 162.0, 162.0, 161.0, 159.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 112.0, 77.0, 77.0, 77.0, 77.0, 92.0, 76.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 105.0, 85.0, 84.0, 84.0, 97.0, 84.0, 84.0, 114.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 128.0, 128.0, 128.0, 127.0, 127.0, 127.0, 127.0, 127.0, 127.0], "value": 127.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 147.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 50.0, 51.0, 53.0, 53.0, 51.0, 53.0, 52.0, 54.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 63.0], "value": 63.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 82.0, 81.0, 82.0, 91.0, 80.0, 81.0, 81.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 131.0, 133.0, 133.0, 134.0, 143.0, 141.0, 143.0, 174.0, 137.0], "value": 141.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 172.0, 172.0, 167.0, 169.0, 171.0, 168.0, 170.0, 166.0, 168.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 64.0, 65.0, 66.0, 64.0, 67.0, 64.0, 64.0, 63.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1519.0000000000002, "alertThreshold": 2.0}]} 09:31:54 INFO - Return code: 0 09:31:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:31:54 INFO - # TBPL SUCCESS # 09:31:54 INFO - Running post-action listener: _resource_record_post_action 09:31:54 INFO - [mozharness: 2016-12-15 17:31:54.948855Z] Finished run-tests step (success) 09:31:54 INFO - Running post-run listener: _resource_record_post_run 09:31:55 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 0; Write bytes: 393838592; Read time: 0; Write time: 242244 09:31:55 INFO - TinderboxPrint: CPU usage
13.2% 09:31:55 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:31:55 INFO - TinderboxPrint: I/O write bytes / time
393,838,592 / 242,244 09:31:55 INFO - TinderboxPrint: CPU idle
8,286.6 (86.8%) 09:31:55 INFO - TinderboxPrint: CPU user
1,211.1 (12.7%) 09:31:55 INFO - TinderboxPrint: Swap in / out
0 / 0 09:31:55 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 105046016; Read time: 0; Write time: 68716 09:31:55 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 287891456; Read time: 0; Write time: 173508 09:31:55 INFO - Running post-run listener: _upload_blobber_files 09:31:55 INFO - Blob upload gear active. 09:31:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:31:55 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:31:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:31:56 INFO - (blobuploader) - INFO - Open directory for files ... 09:31:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:31:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:31:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:59 INFO - (blobuploader) - INFO - Done attempting. 09:31:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:31:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:31:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:59 INFO - (blobuploader) - INFO - Done attempting. 09:31:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:31:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:32:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:00 INFO - (blobuploader) - INFO - Done attempting. 09:32:00 INFO - (blobuploader) - INFO - Iteration through files over. 09:32:00 INFO - Return code: 0 09:32:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:32:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:32:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ec6c7993b5187abb1388de48524a7c15e963889fc25565307962a585c9146289972f86d36ba39cda0024ab5eb54e2bf3efbb9ead602bc6acb3930564755bb5c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a54fe93121ecaf92841bebf009b5d30bc3e04e305cf9ae3b3a7c5b1074d08ddd4b54abba4d5704e1310ac83c243bf969074658b1f9bd1589959115fec56579", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85001fc2ebeca1f8a39a4ae08284ce85c6272bccbd1f7cf0c890636448393faf012977403129bc8a3ce0a7ae5ee68fd9669976c6eb7722e797c9b8c82990b0f3"} 09:32:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:32:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:32:00 INFO - Contents: 09:32:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ec6c7993b5187abb1388de48524a7c15e963889fc25565307962a585c9146289972f86d36ba39cda0024ab5eb54e2bf3efbb9ead602bc6acb3930564755bb5c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a54fe93121ecaf92841bebf009b5d30bc3e04e305cf9ae3b3a7c5b1074d08ddd4b54abba4d5704e1310ac83c243bf969074658b1f9bd1589959115fec56579", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85001fc2ebeca1f8a39a4ae08284ce85c6272bccbd1f7cf0c890636448393faf012977403129bc8a3ce0a7ae5ee68fd9669976c6eb7722e797c9b8c82990b0f3"} 09:32:00 INFO - Running post-run listener: copy_logs_to_upload_dir 09:32:00 INFO - Copying logs to upload dir... 09:32:00 INFO - mkdir: /builds/slave/test/build/upload/logs 09:32:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.439181 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-12-15 09:32:00.527256) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 09:32:00.530888) ========= 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=41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ec6c7993b5187abb1388de48524a7c15e963889fc25565307962a585c9146289972f86d36ba39cda0024ab5eb54e2bf3efbb9ead602bc6acb3930564755bb5c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a54fe93121ecaf92841bebf009b5d30bc3e04e305cf9ae3b3a7c5b1074d08ddd4b54abba4d5704e1310ac83c243bf969074658b1f9bd1589959115fec56579", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85001fc2ebeca1f8a39a4ae08284ce85c6272bccbd1f7cf0c890636448393faf012977403129bc8a3ce0a7ae5ee68fd9669976c6eb7722e797c9b8c82990b0f3"} build_url:https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011514 build_url: 'https://queue.taskcluster.net/v1/task/b7oI9Jj6RoCAKjAaTNA41w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ec6c7993b5187abb1388de48524a7c15e963889fc25565307962a585c9146289972f86d36ba39cda0024ab5eb54e2bf3efbb9ead602bc6acb3930564755bb5c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a54fe93121ecaf92841bebf009b5d30bc3e04e305cf9ae3b3a7c5b1074d08ddd4b54abba4d5704e1310ac83c243bf969074658b1f9bd1589959115fec56579", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85001fc2ebeca1f8a39a4ae08284ce85c6272bccbd1f7cf0c890636448393faf012977403129bc8a3ce0a7ae5ee68fd9669976c6eb7722e797c9b8c82990b0f3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 09:32:00.555479) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:32:00.555776) ========= 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=41a7e32711b8e08598a63237000001d6-1481821878.840756-1698378318 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:32:00.639912) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 09:32:00.640224) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 09:32:00.640475) ========= ========= Total master_lag: 0.18 =========