builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-011 starttime: 1479123010.74 results: success (0) buildid: 20161114004005 builduid: 52ef4f46403c4f9d996936125515ac3e revision: 263190e20543fc7f39c6d41c8fd31cedccdbffd8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 03:30:10.738420) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 03:30:10.738855) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 03:30:10.739188) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 03:30:10.760221) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 03:30:10.760482) ========= 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=5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-14 03:30:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.9M=0s 2016-11-14 03:30:11 (41.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127423 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 03:30:10.902179) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 03:30:10.902495) ========= 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=5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021066 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 03:30:10.953762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 03:30:10.954138) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 263190e20543fc7f39c6d41c8fd31cedccdbffd8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 263190e20543fc7f39c6d41c8fd31cedccdbffd8 --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=5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-14 03:30:12,022 truncating revision to first 12 chars 2016-11-14 03:30:12,022 Setting DEBUG logging. 2016-11-14 03:30:12,022 attempt 1/10 2016-11-14 03:30:12,022 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/263190e20543?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 03:30:12,348 unpacking tar archive at: mozilla-aurora-263190e20543/testing/mozharness/ program finished with exit code 0 elapsedTime=0.658494 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 03:30:11.627927) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 03:30:11.628447) ========= script_repo_revision: 263190e20543fc7f39c6d41c8fd31cedccdbffd8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 03:30:11.628873) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 03:30:11.629176) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 03:30:11.639656) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-11-14 03:30:11.639941) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:30:12 INFO - MultiFileLogger online at 20161114 03:30:12 in /builds/slave/test 03:30:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:30:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:30:12 INFO - {'append_to_log': False, 03:30:12 INFO - 'base_work_dir': '/builds/slave/test', 03:30:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:30:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:30:12 INFO - 'branch': 'Mozilla-Aurora', 03:30:12 INFO - 'buildbot_json_path': 'buildprops.json', 03:30:12 INFO - 'config_files': ('talos/linux_config.py',), 03:30:12 INFO - 'default_actions': ('clobber', 03:30:12 INFO - 'read-buildbot-config', 03:30:12 INFO - 'download-and-extract', 03:30:12 INFO - 'populate-webroot', 03:30:12 INFO - 'create-virtualenv', 03:30:12 INFO - 'install', 03:30:12 INFO - 'run-tests'), 03:30:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:30:12 INFO - 'download_minidump_stackwalk': True, 03:30:12 INFO - 'download_symbols': 'ondemand', 03:30:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:30:12 INFO - 'tooltool.py': '/tools/tooltool.py', 03:30:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:30:12 INFO - '/tools/misc-python/virtualenv.py')}, 03:30:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:30:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:30:12 INFO - 'installer_path': 'installer.exe', 03:30:12 INFO - 'log_level': 'info', 03:30:12 INFO - 'log_name': 'talos', 03:30:12 INFO - 'log_to_console': True, 03:30:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:30:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:30:12 INFO - 'opt_config_files': (), 03:30:12 INFO - 'pip_index': False, 03:30:12 INFO - 'sps_profile': False, 03:30:12 INFO - 'sps_profile_interval': 0, 03:30:12 INFO - 'suite': 'dromaeojs', 03:30:12 INFO - 'system_bits': '32', 03:30:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:30:12 INFO - 'title': 'talos-linux64-ix-011', 03:30:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:30:12 INFO - 'use_talos_json': True, 03:30:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:30:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:30:12 INFO - 'work_dir': 'build'} 03:30:12 INFO - [mozharness: 2016-11-14 11:30:12.805388Z] Running clobber step. 03:30:12 INFO - Running pre-action listener: _resource_record_pre_action 03:30:12 INFO - Running main action method: clobber 03:30:12 INFO - rmtree: /builds/slave/test/build 03:30:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:30:13 INFO - Running post-action listener: _resource_record_post_action 03:30:13 INFO - [mozharness: 2016-11-14 11:30:13.799655Z] Finished clobber step (success) 03:30:13 INFO - [mozharness: 2016-11-14 11:30:13.799754Z] Running read-buildbot-config step. 03:30:13 INFO - Running pre-action listener: _resource_record_pre_action 03:30:13 INFO - Running main action method: read_buildbot_config 03:30:13 INFO - Using buildbot properties: 03:30:13 INFO - { 03:30:13 INFO - "project": "", 03:30:13 INFO - "product": "firefox", 03:30:13 INFO - "script_repo_revision": "production", 03:30:13 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:30:13 INFO - "repository": "", 03:30:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:30:13 INFO - "buildid": "20161114004005", 03:30:13 INFO - "stage_platform": "linux64-pgo", 03:30:13 INFO - "basedir": "/builds/slave/test", 03:30:13 INFO - "nightly_build": "True", 03:30:13 INFO - "buildnumber": 894, 03:30:13 INFO - "slavename": "talos-linux64-ix-011", 03:30:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:30:13 INFO - "platform": "ubuntu64_hw", 03:30:13 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:30:13 INFO - "branch": "mozilla-aurora", 03:30:13 INFO - "repo_path": "releases/mozilla-aurora", 03:30:13 INFO - "revision": "263190e20543fc7f39c6d41c8fd31cedccdbffd8", 03:30:13 INFO - "pgo_build": "True", 03:30:13 INFO - "builduid": "52ef4f46403c4f9d996936125515ac3e", 03:30:13 INFO - "slavebuilddir": "test" 03:30:13 INFO - } 03:30:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 03:30:13 INFO - Running post-action listener: _resource_record_post_action 03:30:13 INFO - [mozharness: 2016-11-14 11:30:13.802281Z] Finished read-buildbot-config step (success) 03:30:13 INFO - [mozharness: 2016-11-14 11:30:13.802371Z] Running download-and-extract step. 03:30:13 INFO - Running pre-action listener: _resource_record_pre_action 03:30:13 INFO - Running main action method: download_and_extract 03:30:13 INFO - mkdir: /builds/slave/test/build/tests 03:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:13 INFO - trying https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:30:13 INFO - Downloading https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:30:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:30:14 INFO - Downloaded 1361 bytes. 03:30:14 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:30:14 INFO - Using the following test package requirements: 03:30:14 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:30:14 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:14 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:30:14 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:14 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:30:14 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:14 INFO - u'jsshell-linux-x86_64.zip'], 03:30:14 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:14 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:30:14 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:30:14 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:14 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:30:14 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:14 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:30:14 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:14 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:30:14 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:14 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:30:14 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:30:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:30:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:30:14 INFO - Fetch https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:30:14 INFO - Expected file size: 21962078 03:30:19 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:30:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:30:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:30:19 INFO - Fetch https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:30:20 INFO - Expected file size: 21962078 03:30:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 03:30:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:30:24 INFO - Fetch https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 03:30:25 INFO - Expected file size: 14414089 03:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:26 INFO - trying https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:30:26 INFO - Downloading https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:30:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:30:27 INFO - Downloaded 64062232 bytes. 03:30:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:30:27 INFO - mkdir: /builds/slave/test/properties 03:30:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:30:27 INFO - Writing to file /builds/slave/test/properties/build_url 03:30:27 INFO - Contents: 03:30:27 INFO - build_url:https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:30:27 INFO - Running post-action listener: _resource_record_post_action 03:30:27 INFO - Running post-action listener: set_extra_try_arguments 03:30:27 INFO - [mozharness: 2016-11-14 11:30:27.874401Z] Finished download-and-extract step (success) 03:30:27 INFO - [mozharness: 2016-11-14 11:30:27.874629Z] Running populate-webroot step. 03:30:27 INFO - Running pre-action listener: _resource_record_pre_action 03:30:27 INFO - Running main action method: populate_webroot 03:30:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:30:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:30:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:30:27 INFO - '--tppagecycles', 03:30:27 INFO - '7'], 03:30:27 INFO - 'tests': ['tsvgm']}}, 03:30:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:30:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:30:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:30:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:30:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:30:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:30:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:27 INFO - 'talos_options': ['--disable-e10s'], 03:30:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:30:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:30:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:27 INFO - 'talos_options': ['--disable-e10s'], 03:30:27 INFO - 'tests': ['damp', 'tps']}, 03:30:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:27 INFO - 'tests': ['damp', 'tps']}, 03:30:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:30:27 INFO - 'tests': ['dromaeo_dom']}, 03:30:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:30:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:30:27 INFO - 'tests': ['basic_compositor_video']}, 03:30:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:30:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:30:27 INFO - 'tests': ['a11yr', 03:30:27 INFO - 'ts_paint', 03:30:27 INFO - 'tpaint', 03:30:27 INFO - 'sessionrestore', 03:30:27 INFO - 'sessionrestore_no_auto_restore', 03:30:27 INFO - 'tabpaint']}, 03:30:27 INFO - 'other-e10s': {'tests': ['a11yr', 03:30:27 INFO - 'ts_paint', 03:30:27 INFO - 'tpaint', 03:30:27 INFO - 'sessionrestore', 03:30:27 INFO - 'sessionrestore_no_auto_restore', 03:30:27 INFO - 'tabpaint']}, 03:30:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:30:27 INFO - 'ts_paint', 03:30:27 INFO - 'tpaint', 03:30:27 INFO - 'sessionrestore', 03:30:27 INFO - 'sessionrestore_no_auto_restore', 03:30:27 INFO - 'tabpaint']}, 03:30:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:30:27 INFO - 'ts_paint', 03:30:27 INFO - 'tpaint', 03:30:27 INFO - 'sessionrestore', 03:30:27 INFO - 'sessionrestore_no_auto_restore', 03:30:27 INFO - 'tabpaint']}, 03:30:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:30:27 INFO - 'tests': ['a11yr', 03:30:27 INFO - 'ts_paint', 03:30:27 INFO - 'tpaint', 03:30:27 INFO - 'sessionrestore', 03:30:27 INFO - 'sessionrestore_no_auto_restore', 03:30:27 INFO - 'tabpaint']}, 03:30:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:30:27 INFO - 'tests': ['a11yr', 03:30:27 INFO - 'ts_paint', 03:30:27 INFO - 'tpaint', 03:30:27 INFO - 'sessionrestore', 03:30:27 INFO - 'sessionrestore_no_auto_restore', 03:30:27 INFO - 'tabpaint']}, 03:30:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:30:27 INFO - 'tests': ['tsvgx', 03:30:27 INFO - 'tsvgr_opacity', 03:30:27 INFO - 'tart', 03:30:27 INFO - 'tscrollx', 03:30:27 INFO - 'cart']}, 03:30:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:30:27 INFO - 'tsvgr_opacity', 03:30:27 INFO - 'tart', 03:30:27 INFO - 'tscrollx', 03:30:27 INFO - 'cart']}, 03:30:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:27 INFO - 'talos_options': ['--disable-e10s'], 03:30:27 INFO - 'tests': ['tp5o']}, 03:30:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:27 INFO - 'tests': ['tp5o']}, 03:30:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:27 INFO - 'talos_options': ['--disable-e10s', 03:30:27 INFO - '--xperf_path', 03:30:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:30:27 INFO - 'tests': ['tp5n']}, 03:30:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:27 INFO - 'talos_options': ['--xperf_path', 03:30:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:30:27 INFO - 'tests': ['tp5n']}}, 03:30:27 INFO - 'talos.zip': {'path': '', 03:30:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:30:27 INFO - Running post-action listener: _resource_record_post_action 03:30:27 INFO - [mozharness: 2016-11-14 11:30:27.895958Z] Finished populate-webroot step (success) 03:30:27 INFO - [mozharness: 2016-11-14 11:30:27.896045Z] Running create-virtualenv step. 03:30:27 INFO - Running pre-action listener: _resource_record_pre_action 03:30:27 INFO - Running main action method: create_virtualenv 03:30:27 INFO - Creating virtualenv /builds/slave/test/build/venv 03:30:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:30:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:30:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:30:27 INFO - Using real prefix '/usr' 03:30:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:30:28 INFO - Installing distribute.............................................................................................................................................................................................done. 03:30:30 INFO - Installing pip.................done. 03:30:30 INFO - Return code: 0 03:30:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:30 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 0x7fcfb4e46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ccf60>, '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 0x16cdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ce1d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:30:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:30:30 INFO - Using env: {'DISPLAY': ':0', 03:30:30 INFO - 'HOME': '/home/cltbld', 03:30:30 INFO - 'LANG': 'en_US.UTF-8', 03:30:30 INFO - 'LANGUAGE': 'en_US:en', 03:30:30 INFO - 'LOGNAME': 'cltbld', 03:30:30 INFO - 'MAIL': '/var/mail/cltbld', 03:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:30 INFO - 'MOZ_NO_REMOTE': '1', 03:30:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:30 INFO - 'NO_EM_RESTART': '1', 03:30:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:30 INFO - 'PWD': '/builds/slave/test', 03:30:30 INFO - 'SHELL': '/bin/bash', 03:30:30 INFO - 'SHLVL': '1', 03:30:30 INFO - 'TERM': 'linux', 03:30:30 INFO - 'TMOUT': '86400', 03:30:30 INFO - 'USER': 'cltbld', 03:30:30 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 03:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:30 INFO - '_': '/tools/buildbot/bin/python'} 03:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:30:30 INFO - Cleaning up... 03:30:30 INFO - Return code: 0 03:30:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:30 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 0x7fcfb4e46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ccf60>, '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 0x16cdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ce1d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:30:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:30:30 INFO - Using env: {'DISPLAY': ':0', 03:30:30 INFO - 'HOME': '/home/cltbld', 03:30:30 INFO - 'LANG': 'en_US.UTF-8', 03:30:30 INFO - 'LANGUAGE': 'en_US:en', 03:30:30 INFO - 'LOGNAME': 'cltbld', 03:30:30 INFO - 'MAIL': '/var/mail/cltbld', 03:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:30 INFO - 'MOZ_NO_REMOTE': '1', 03:30:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:30 INFO - 'NO_EM_RESTART': '1', 03:30:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:30 INFO - 'PWD': '/builds/slave/test', 03:30:30 INFO - 'SHELL': '/bin/bash', 03:30:30 INFO - 'SHLVL': '1', 03:30:30 INFO - 'TERM': 'linux', 03:30:30 INFO - 'TMOUT': '86400', 03:30:30 INFO - 'USER': 'cltbld', 03:30:30 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 03:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:30 INFO - '_': '/tools/buildbot/bin/python'} 03:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:30 INFO - Downloading/unpacking psutil>=3.1.1 03:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:30:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:30:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:30:32 INFO - Installing collected packages: psutil 03:30:32 INFO - Running setup.py install for psutil 03:30:33 INFO - building 'psutil._psutil_linux' extension 03:30:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:30:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:30:33 INFO - building 'psutil._psutil_posix' extension 03:30:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:30:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:30:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:30:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:30:33 INFO - Successfully installed psutil 03:30:33 INFO - Cleaning up... 03:30:33 INFO - Return code: 0 03:30:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:33 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 0x7fcfb4e46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ccf60>, '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 0x16cdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ce1d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:30:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:30:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:30:33 INFO - Using env: {'DISPLAY': ':0', 03:30:33 INFO - 'HOME': '/home/cltbld', 03:30:33 INFO - 'LANG': 'en_US.UTF-8', 03:30:33 INFO - 'LANGUAGE': 'en_US:en', 03:30:33 INFO - 'LOGNAME': 'cltbld', 03:30:33 INFO - 'MAIL': '/var/mail/cltbld', 03:30:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:33 INFO - 'MOZ_NO_REMOTE': '1', 03:30:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:33 INFO - 'NO_EM_RESTART': '1', 03:30:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:33 INFO - 'PWD': '/builds/slave/test', 03:30:33 INFO - 'SHELL': '/bin/bash', 03:30:33 INFO - 'SHLVL': '1', 03:30:33 INFO - 'TERM': 'linux', 03:30:33 INFO - 'TMOUT': '86400', 03:30:33 INFO - 'USER': 'cltbld', 03:30:33 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 03:30:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:33 INFO - '_': '/tools/buildbot/bin/python'} 03:30:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:30:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:30:35 INFO - Installing collected packages: mozsystemmonitor 03:30:35 INFO - Running setup.py install for mozsystemmonitor 03:30:35 INFO - Successfully installed mozsystemmonitor 03:30:35 INFO - Cleaning up... 03:30:35 INFO - Return code: 0 03:30:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:35 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 0x7fcfb4e46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ccf60>, '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 0x16cdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ce1d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:30:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:30:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:30:35 INFO - Using env: {'DISPLAY': ':0', 03:30:35 INFO - 'HOME': '/home/cltbld', 03:30:35 INFO - 'LANG': 'en_US.UTF-8', 03:30:35 INFO - 'LANGUAGE': 'en_US:en', 03:30:35 INFO - 'LOGNAME': 'cltbld', 03:30:35 INFO - 'MAIL': '/var/mail/cltbld', 03:30:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:35 INFO - 'MOZ_NO_REMOTE': '1', 03:30:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:35 INFO - 'NO_EM_RESTART': '1', 03:30:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:35 INFO - 'PWD': '/builds/slave/test', 03:30:35 INFO - 'SHELL': '/bin/bash', 03:30:35 INFO - 'SHLVL': '1', 03:30:35 INFO - 'TERM': 'linux', 03:30:35 INFO - 'TMOUT': '86400', 03:30:35 INFO - 'USER': 'cltbld', 03:30:35 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 03:30:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:35 INFO - '_': '/tools/buildbot/bin/python'} 03:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:36 INFO - Downloading/unpacking jsonschema==2.5.1 03:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:30:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:30: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 03:30: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 03:30: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 03:30: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 03:30: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 03:30: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 03:30:38 INFO - Downloading functools32-3.2.3-2.tar.gz 03:30:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:30:38 INFO - warning: no files found matching '*.txt' 03:30:38 INFO - no previously-included directories found matching 'build' 03:30:38 INFO - no previously-included directories found matching 'dist' 03:30:38 INFO - no previously-included directories found matching '.git*' 03:30:38 INFO - Installing collected packages: jsonschema, functools32 03:30:38 INFO - Running setup.py install for functools32 03:30:38 INFO - warning: no files found matching '*.txt' 03:30:38 INFO - no previously-included directories found matching 'build' 03:30:38 INFO - no previously-included directories found matching 'dist' 03:30:38 INFO - no previously-included directories found matching '.git*' 03:30:38 INFO - Successfully installed jsonschema functools32 03:30:38 INFO - Cleaning up... 03:30:38 INFO - Return code: 0 03:30:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:38 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 0x7fcfb4e46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ccf60>, '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 0x16cdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ce1d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:30:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:30:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:30:38 INFO - Using env: {'DISPLAY': ':0', 03:30:38 INFO - 'HOME': '/home/cltbld', 03:30:38 INFO - 'LANG': 'en_US.UTF-8', 03:30:38 INFO - 'LANGUAGE': 'en_US:en', 03:30:38 INFO - 'LOGNAME': 'cltbld', 03:30:38 INFO - 'MAIL': '/var/mail/cltbld', 03:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:38 INFO - 'MOZ_NO_REMOTE': '1', 03:30:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:38 INFO - 'NO_EM_RESTART': '1', 03:30:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:38 INFO - 'PWD': '/builds/slave/test', 03:30:38 INFO - 'SHELL': '/bin/bash', 03:30:38 INFO - 'SHLVL': '1', 03:30:38 INFO - 'TERM': 'linux', 03:30:38 INFO - 'TMOUT': '86400', 03:30:38 INFO - 'USER': 'cltbld', 03:30:38 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 03:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:38 INFO - '_': '/tools/buildbot/bin/python'} 03:30:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:30:38 INFO - Cleaning up... 03:30:38 INFO - Return code: 0 03:30:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:38 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 0x7fcfb4e46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ccf60>, '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 0x16cdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ce1d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:30:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:30:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:30:38 INFO - Using env: {'DISPLAY': ':0', 03:30:38 INFO - 'HOME': '/home/cltbld', 03:30:38 INFO - 'LANG': 'en_US.UTF-8', 03:30:38 INFO - 'LANGUAGE': 'en_US:en', 03:30:38 INFO - 'LOGNAME': 'cltbld', 03:30:38 INFO - 'MAIL': '/var/mail/cltbld', 03:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:38 INFO - 'MOZ_NO_REMOTE': '1', 03:30:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:38 INFO - 'NO_EM_RESTART': '1', 03:30:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:38 INFO - 'PWD': '/builds/slave/test', 03:30:38 INFO - 'SHELL': '/bin/bash', 03:30:38 INFO - 'SHLVL': '1', 03:30:38 INFO - 'TERM': 'linux', 03:30:38 INFO - 'TMOUT': '86400', 03:30:38 INFO - 'USER': 'cltbld', 03:30:38 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 03:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:38 INFO - '_': '/tools/buildbot/bin/python'} 03:30:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:38 INFO - Downloading/unpacking blobuploader==1.2.4 03:30: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 03:30: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 03:30: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 03:30: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 03:30: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 03:30: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 03:30:40 INFO - Downloading blobuploader-1.2.4.tar.gz 03:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:30:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:30: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 03:30: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 03:30: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 03:30: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 03:30: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 03:30: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 03:30:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:30:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:41 INFO - Downloading docopt-0.6.1.tar.gz 03:30:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:30:41 INFO - Installing collected packages: blobuploader, requests, docopt 03:30:41 INFO - Running setup.py install for blobuploader 03:30:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:30:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:30:41 INFO - Running setup.py install for requests 03:30:42 INFO - Running setup.py install for docopt 03:30:42 INFO - Successfully installed blobuploader requests docopt 03:30:42 INFO - Cleaning up... 03:30:42 INFO - Return code: 0 03:30:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:30:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:42 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 0x7fcfb4e46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ccf60>, '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 0x16cdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ce1d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:30:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:30:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:30:42 INFO - Using env: {'DISPLAY': ':0', 03:30:42 INFO - 'HOME': '/home/cltbld', 03:30:42 INFO - 'LANG': 'en_US.UTF-8', 03:30:42 INFO - 'LANGUAGE': 'en_US:en', 03:30:42 INFO - 'LOGNAME': 'cltbld', 03:30:42 INFO - 'MAIL': '/var/mail/cltbld', 03:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:42 INFO - 'MOZ_NO_REMOTE': '1', 03:30:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:42 INFO - 'NO_EM_RESTART': '1', 03:30:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:42 INFO - 'PWD': '/builds/slave/test', 03:30:42 INFO - 'SHELL': '/bin/bash', 03:30:42 INFO - 'SHLVL': '1', 03:30:42 INFO - 'TERM': 'linux', 03:30:42 INFO - 'TMOUT': '86400', 03:30:42 INFO - 'USER': 'cltbld', 03:30:42 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 03:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:42 INFO - '_': '/tools/buildbot/bin/python'} 03:30:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:30:42 INFO - Running setup.py (path:/tmp/pip-OYhaLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:30:42 INFO - Running setup.py (path:/tmp/pip-2pbM8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:30:42 INFO - Running setup.py (path:/tmp/pip-YHSi3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:30:42 INFO - Running setup.py (path:/tmp/pip-E5oo8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:30:42 INFO - Running setup.py (path:/tmp/pip-uKsUpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:30:42 INFO - Running setup.py (path:/tmp/pip-Sii0YV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:30:42 INFO - Running setup.py (path:/tmp/pip-r3Pn6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:30:42 INFO - Running setup.py (path:/tmp/pip-MvFq2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:30:43 INFO - Running setup.py (path:/tmp/pip-Jmd4v6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:30:43 INFO - Running setup.py (path:/tmp/pip-glfvy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:30:43 INFO - Running setup.py (path:/tmp/pip-KEfBRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:30:43 INFO - Running setup.py (path:/tmp/pip-uxZg3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:30:43 INFO - Running setup.py (path:/tmp/pip-gHnsUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:30:43 INFO - Running setup.py (path:/tmp/pip-7JyMtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:43 INFO - Running setup.py (path:/tmp/pip-2nLr3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:30:43 INFO - Running setup.py (path:/tmp/pip-gTUom_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:30:43 INFO - Running setup.py (path:/tmp/pip-u_oTuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:30:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:30:43 INFO - Running setup.py install for manifestparser 03:30:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:30:43 INFO - Running setup.py install for mozcrash 03:30:43 INFO - Running setup.py install for mozdebug 03:30:43 INFO - Running setup.py install for mozdevice 03:30:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:30:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:30:44 INFO - Running setup.py install for mozfile 03:30:44 INFO - Running setup.py install for mozhttpd 03:30:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:30:44 INFO - Running setup.py install for mozinfo 03:30:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:30:44 INFO - Running setup.py install for mozInstall 03:30:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:30:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:30:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:30:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:30:44 INFO - Running setup.py install for mozleak 03:30:44 INFO - Running setup.py install for mozlog 03:30:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:30:44 INFO - Running setup.py install for moznetwork 03:30:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:30:44 INFO - Running setup.py install for mozprocess 03:30:44 INFO - Running setup.py install for mozprofile 03:30:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:30:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:30:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:30:45 INFO - Running setup.py install for mozrunner 03:30:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:30:45 INFO - Running setup.py install for mozscreenshot 03:30:45 INFO - Running setup.py install for moztest 03:30:45 INFO - Running setup.py install for mozversion 03:30:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:30:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:30:45 INFO - Cleaning up... 03:30:45 INFO - Return code: 0 03:30:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:30: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')]} 03:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30: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')]} 03:30:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:45 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 0x7fcfb4e46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ccf60>, '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 0x16cdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ce1d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:30:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:30:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:30:45 INFO - Using env: {'DISPLAY': ':0', 03:30:45 INFO - 'HOME': '/home/cltbld', 03:30:45 INFO - 'LANG': 'en_US.UTF-8', 03:30:45 INFO - 'LANGUAGE': 'en_US:en', 03:30:45 INFO - 'LOGNAME': 'cltbld', 03:30:45 INFO - 'MAIL': '/var/mail/cltbld', 03:30:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:45 INFO - 'MOZ_NO_REMOTE': '1', 03:30:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:45 INFO - 'NO_EM_RESTART': '1', 03:30:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:45 INFO - 'PWD': '/builds/slave/test', 03:30:45 INFO - 'SHELL': '/bin/bash', 03:30:45 INFO - 'SHLVL': '1', 03:30:45 INFO - 'TERM': 'linux', 03:30:45 INFO - 'TMOUT': '86400', 03:30:45 INFO - 'USER': 'cltbld', 03:30:45 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 03:30:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:45 INFO - '_': '/tools/buildbot/bin/python'} 03:30:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:30:45 INFO - Running setup.py (path:/tmp/pip-hFlGhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:30:45 INFO - Running setup.py (path:/tmp/pip-J1fboP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:30:45 INFO - Running setup.py (path:/tmp/pip-X9XqHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:30:45 INFO - Running setup.py (path:/tmp/pip-Gk9jRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:30:45 INFO - Running setup.py (path:/tmp/pip-2Q4q1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:30:46 INFO - Running setup.py (path:/tmp/pip-lI09bj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:30:46 INFO - Running setup.py (path:/tmp/pip-o6kGDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:30:46 INFO - Running setup.py (path:/tmp/pip-b1VvwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:30:46 INFO - Running setup.py (path:/tmp/pip-ei5AlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:30:46 INFO - Running setup.py (path:/tmp/pip-xZTomR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:30:46 INFO - Running setup.py (path:/tmp/pip-DWeWKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:30:46 INFO - Running setup.py (path:/tmp/pip-MPWONc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:30:46 INFO - Running setup.py (path:/tmp/pip-IzQvrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:30:46 INFO - Running setup.py (path:/tmp/pip-iK4jDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:46 INFO - Running setup.py (path:/tmp/pip-sw6NuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:30:46 INFO - Running setup.py (path:/tmp/pip-7dqCuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:30:46 INFO - Running setup.py (path:/tmp/pip-wnGKmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:30:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:30: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 03:30: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 03:30: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 03:30: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 03:30: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 03:30: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 03:30:48 INFO - Downloading blessings-1.6.tar.gz 03:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:30:48 INFO - Installing collected packages: blessings 03:30:48 INFO - Running setup.py install for blessings 03:30:49 INFO - Successfully installed blessings 03:30:49 INFO - Cleaning up... 03:30:49 INFO - Return code: 0 03:30:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:30:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:30:49 INFO - Reading from file tmpfile_stdout 03:30:49 INFO - Current package versions: 03:30:49 INFO - argparse == 1.2.1 03:30:49 INFO - blessings == 1.6 03:30:49 INFO - blobuploader == 1.2.4 03:30:49 INFO - docopt == 0.6.1 03:30:49 INFO - functools32 == 3.2.3-2 03:30:49 INFO - jsonschema == 2.5.1 03:30:49 INFO - manifestparser == 1.1 03:30:49 INFO - mozInstall == 1.12 03:30:49 INFO - mozcrash == 0.17 03:30:49 INFO - mozdebug == 0.1 03:30:49 INFO - mozdevice == 0.48 03:30:49 INFO - mozfile == 1.2 03:30:49 INFO - mozhttpd == 0.7 03:30:49 INFO - mozinfo == 0.9 03:30:49 INFO - mozleak == 0.1 03:30:49 INFO - mozlog == 3.4 03:30:49 INFO - moznetwork == 0.27 03:30:49 INFO - mozprocess == 0.23 03:30:49 INFO - mozprofile == 0.28 03:30:49 INFO - mozrunner == 6.12 03:30:49 INFO - mozscreenshot == 0.1 03:30:49 INFO - mozsystemmonitor == 0.3 03:30:49 INFO - moztest == 0.7 03:30:49 INFO - mozversion == 1.4 03:30:49 INFO - psutil == 3.1.1 03:30:49 INFO - requests == 1.2.3 03:30:49 INFO - wsgiref == 0.1.2 03:30:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:30: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')]} 03:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30: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')]} 03:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:49 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 0x7fcfb4e46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ccf60>, '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 0x16cdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ce1d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:30:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:30:49 INFO - Using env: {'DISPLAY': ':0', 03:30:49 INFO - 'HOME': '/home/cltbld', 03:30:49 INFO - 'LANG': 'en_US.UTF-8', 03:30:49 INFO - 'LANGUAGE': 'en_US:en', 03:30:49 INFO - 'LOGNAME': 'cltbld', 03:30:49 INFO - 'MAIL': '/var/mail/cltbld', 03:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:49 INFO - 'MOZ_NO_REMOTE': '1', 03:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:49 INFO - 'NO_EM_RESTART': '1', 03:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:49 INFO - 'PWD': '/builds/slave/test', 03:30:49 INFO - 'SHELL': '/bin/bash', 03:30:49 INFO - 'SHLVL': '1', 03:30:49 INFO - 'TERM': 'linux', 03:30:49 INFO - 'TMOUT': '86400', 03:30:49 INFO - 'USER': 'cltbld', 03:30:49 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 03:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:49 INFO - '_': '/tools/buildbot/bin/python'} 03:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:30:49 INFO - Cleaning up... 03:30:49 INFO - Return code: 0 03:30:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:30: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')]} 03:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30: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')]} 03:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:49 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 0x7fcfb4e46f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ccf60>, '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 0x16cdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ce1d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:30:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:30:49 INFO - Using env: {'DISPLAY': ':0', 03:30:49 INFO - 'HOME': '/home/cltbld', 03:30:49 INFO - 'LANG': 'en_US.UTF-8', 03:30:49 INFO - 'LANGUAGE': 'en_US:en', 03:30:49 INFO - 'LOGNAME': 'cltbld', 03:30:49 INFO - 'MAIL': '/var/mail/cltbld', 03:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:49 INFO - 'MOZ_NO_REMOTE': '1', 03:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:49 INFO - 'NO_EM_RESTART': '1', 03:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:49 INFO - 'PWD': '/builds/slave/test', 03:30:49 INFO - 'SHELL': '/bin/bash', 03:30:49 INFO - 'SHLVL': '1', 03:30:49 INFO - 'TERM': 'linux', 03:30:49 INFO - 'TMOUT': '86400', 03:30:49 INFO - 'USER': 'cltbld', 03:30:49 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 03:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:49 INFO - '_': '/tools/buildbot/bin/python'} 03:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:30:49 INFO - Cleaning up... 03:30:49 INFO - Return code: 0 03:30:49 INFO - Running post-action listener: _resource_record_post_action 03:30:49 INFO - Running post-action listener: _start_resource_monitoring 03:30:49 INFO - Starting resource monitoring. 03:30:49 INFO - [mozharness: 2016-11-14 11:30:49.803523Z] Finished create-virtualenv step (success) 03:30:49 INFO - [mozharness: 2016-11-14 11:30:49.803976Z] Running install step. 03:30:49 INFO - Running pre-action listener: _resource_record_pre_action 03:30:49 INFO - Running main action method: install 03:30:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:30:50 INFO - Reading from file tmpfile_stdout 03:30:50 INFO - Detecting whether we're running mozinstall >=1.0... 03:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:30:50 INFO - Reading from file tmpfile_stdout 03:30:50 INFO - Output received: 03:30:50 INFO - Usage: mozinstall [options] installer 03:30:50 INFO - Options: 03:30:50 INFO - -h, --help show this help message and exit 03:30:50 INFO - -d DEST, --destination=DEST 03:30:50 INFO - Directory to install application into. [default: 03:30:50 INFO - "/builds/slave/test"] 03:30:50 INFO - --app=APP Application being installed. [default: firefox] 03:30:50 INFO - mkdir: /builds/slave/test/build/application 03:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:31:06 INFO - Reading from file tmpfile_stdout 03:31:06 INFO - Output received: 03:31:06 INFO - /builds/slave/test/build/application/firefox/firefox 03:31:06 INFO - Running post-action listener: _resource_record_post_action 03:31:06 INFO - [mozharness: 2016-11-14 11:31:06.157893Z] Finished install step (success) 03:31:06 INFO - [mozharness: 2016-11-14 11:31:06.158140Z] Running run-tests step. 03:31:06 INFO - Running pre-action listener: _resource_record_pre_action 03:31:06 INFO - Running main action method: run_tests 03:31:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:31:06 INFO - Python 2.7.3 03:31:06 INFO - Return code: 0 03:31:06 INFO - grabbing minidump binary from tooltool 03:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:06 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 0x16ccf60>, '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 0x16cdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16ce1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:31:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:31:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:31:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:31:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:31:06 INFO - Return code: 0 03:31:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:31:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:31:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:31:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:31:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:31:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:31:06 INFO - Using env: {'DISPLAY': ':0', 03:31:06 INFO - 'HOME': '/home/cltbld', 03:31:06 INFO - 'LANG': 'en_US.UTF-8', 03:31:06 INFO - 'LANGUAGE': 'en_US:en', 03:31:06 INFO - 'LOGNAME': 'cltbld', 03:31:06 INFO - 'MAIL': '/var/mail/cltbld', 03:31:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:31:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:06 INFO - 'MOZ_NO_REMOTE': '1', 03:31:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:06 INFO - 'NO_EM_RESTART': '1', 03:31:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:06 INFO - 'PWD': '/builds/slave/test', 03:31:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:31:06 INFO - 'SHELL': '/bin/bash', 03:31:06 INFO - 'SHLVL': '1', 03:31:06 INFO - 'TERM': 'linux', 03:31:06 INFO - 'TMOUT': '86400', 03:31:06 INFO - 'USER': 'cltbld', 03:31:06 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078', 03:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:06 INFO - '_': '/tools/buildbot/bin/python'} 03:31:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:31:06 INFO - mozversion application_buildid: 20161114004005 03:31:06 INFO - mozversion application_changeset: 263190e20543fc7f39c6d41c8fd31cedccdbffd8 03:31:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:31:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:31:06 INFO - mozversion application_name: Firefox 03:31:06 INFO - mozversion application_remotingname: firefox-dev 03:31:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:31:06 INFO - mozversion application_vendor: Mozilla 03:31:06 INFO - mozversion application_version: 51.0a2 03:31:06 INFO - mozversion platform_buildid: 20161114004005 03:31:06 INFO - mozversion platform_changeset: 263190e20543fc7f39c6d41c8fd31cedccdbffd8 03:31:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:31:06 INFO - mozversion platform_version: 51.0a2 03:31:06 INFO - using testdate: 1479123066 03:31:06 INFO - actual date: 1479123066 03:31:06 INFO - starting webserver on 'localhost:34917' 03:31:06 INFO - SUITE-START | Running 2 tests 03:31:06 INFO - TEST-START | dromaeo_css 03:31:06 INFO - operating with platform_type : linux_ 03:31:06 INFO - Initialising browser for dromaeo_css test... 03:31:06 INFO - TEST-INFO | started process 8978 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFZ1iK_/profile http://localhost:34917/getInfo.html) 03:31:11 INFO - PROCESS | 8978 | __metrics Screen width/height:1600/1200 03:31:11 INFO - PROCESS | 8978 | colorDepth:24 03:31:11 INFO - PROCESS | 8978 | Browser inner width/height: 1024/697 03:31:11 INFO - PROCESS | 8978 | __metrics 03:31:12 INFO - PROCESS | 8978 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:31:12 INFO - PROCESS | 8978 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:31:12 INFO - PROCESS | 8978 | console.error: PushService: 03:31:12 INFO - PROCESS | 8978 | stateChangeProcessEnqueue: Error transitioning state 03:31:12 INFO - PROCESS | 8978 | UnknownError 03:31:13 INFO - TEST-INFO | 8978: exit 0 03:31:13 INFO - Browser initialized. 03:31:13 INFO - Running cycle 1/1 for dromaeo_css test... 03:31:13 INFO - TEST-INFO | started process 9067 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFZ1iK_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:31:25 INFO - PROCESS | 9067 | RSS: Main: 145088512 03:31:25 INFO - PROCESS | 9067 | 03:32:16 INFO - PROCESS | 9067 | 1479123136233 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:34:25 INFO - PROCESS | 9067 | Cycle 1(1): loaded http://localhost:34917/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34917/tests/dromaeo/cssquery-ext.html) 03:34:26 INFO - PROCESS | 9067 | RSS: Main: 239865856 03:34:26 INFO - PROCESS | 9067 | 03:37:28 INFO - PROCESS | 9067 | Cycle 1(1): loaded http://localhost:34917/tests/dromaeo/cssquery-ext.html (next: http://localhost:34917/tests/dromaeo/cssquery-jquery.html) 03:37:29 INFO - PROCESS | 9067 | RSS: Main: 230764544 03:37:29 INFO - PROCESS | 9067 | 03:40:31 INFO - PROCESS | 9067 | Cycle 1(1): loaded http://localhost:34917/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34917/tests/dromaeo/cssquery-mootools.html) 03:40:31 INFO - PROCESS | 9067 | RSS: Main: 177418240 03:40:31 INFO - PROCESS | 9067 | 03:43:33 INFO - PROCESS | 9067 | Cycle 1(1): loaded http://localhost:34917/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34917/tests/dromaeo/cssquery-prototype.html) 03:43:34 INFO - PROCESS | 9067 | RSS: Main: 216637440 03:43:34 INFO - PROCESS | 9067 | 03:46:36 INFO - PROCESS | 9067 | Cycle 1(1): loaded http://localhost:34917/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34917/tests/dromaeo/cssquery-yui.html) 03:46:37 INFO - PROCESS | 9067 | RSS: Main: 177598464 03:46:37 INFO - PROCESS | 9067 | 03:49:40 INFO - PROCESS | 9067 | Cycle 1(1): loaded http://localhost:34917/tests/dromaeo/cssquery-yui.html (next: http://localhost:34917/tests/dromaeo/cssquery-dojo.html) 03:49:40 INFO - PROCESS | 9067 | RSS: Main: 254337024 03:49:40 INFO - PROCESS | 9067 | 03:49:40 INFO - PROCESS | 9067 | __start_tp_report 03:49:40 INFO - PROCESS | 9067 | _x_x_mozilla_page_load 03:49:40 INFO - PROCESS | 9067 | _x_x_mozilla_page_load_details 03:49:40 INFO - PROCESS | 9067 | |i|pagename|runs| 03:49:40 INFO - PROCESS | 9067 | |0;dojo.html;3093;3129.7809153359267;3135.608048993877;3162.968370316296;3171.365726854628;5431.185322201672;5492.093804522254;5503.036968530511;5510.201020851984;5521.116621340586;5467.528458037463;5502.422245664197;5504.311961004874;5505.201745746865;5505.394406615279;4967.503249675031;4987.551120399164;5013.298138260647;5050.924236136461;5082.64421490494;4928.211486162215;5021.84934451967;5078.984203159364;5081.034603425356;5104.5405913467785;4902.926456103161;4911.82808601699;4933.605311575084;4980.9252861207115;4982.00359928014;4955.231939049435;4977.776000079987;4979.601631869442;5026.623003274769;5048.873778155576;4912.926306105412;4918.458969513351;4992.350994370745;5008.173651347523;5019.774110165052;5179.404368497596;5288.68267903927;5301.602379821529;5405.702686352249;5443.319585051869;5659.773609055633;5712.285964254468;5715.3141623005085;5791.160281759164;5799;5829.650220986754;5835.328937172237;5837.678927658977;5841.7371218295275;5843.53251739864;5267.578593712494;5310.468953104697;5378.8924221515745;5425.131978883439;5434.782608695687;5945.16767652529;5951.226340575784;5969.970150149308;5970;5984.431479009487;5731.340895796953;5750.1949727038245;5750.654960702372;5753.223314852525;5756.798512052058;5706;5764.193012978187;5776.855578610568;5814.418558144236;5833.941660583339;5867.735951882176;5883.646981181143;5884.852878677982;5889.057750759858;5907.202527658711;5779.537636989031;5840.8610320987;5846.649201047951;5852.239208902815;5863.853403664857;4314.827406903752;4359.738415695068;4438.134563760101;4491.8203271869415;4506.864794056183;4009.278329900646;4033.6369726724733;4060.39094135882;4061.1065565575527;4068.2880495913096;5665.943340566624;5677.347105082886;5681.687507187145;5686.402927692533;5688.232088667977;5698.572607054488;5719.542436605062;5756.366799652035;5925.170476133345;5933.910991335133;5525.778968841201;5581.2186293918885;5584.944150558443;5612.635178713371;5617.691026993554;5104.770285337168;5109.795608175706;5113.744312784346;5114.71869047206;5139.100657384943;5800.60122729865;5903.586748927534;6036.034234522544;6042.063480160582;6051.7579296827635;5836.328822185334;5881.38245484218;5900.5574581907385;5938.049912014057;5939.346671866005;6133.325334213144;6134.539909506716;6138.570300078952;6138.785142519991;6147.231596050494;5570.498655121059;5829.183914251923;5829.854253643693;5832.212651292021;5832.883342333044;5712.514436272797;5847.327557330961;5889.852753681193;5890.941090589039;5968.76124954997;5740.741666625096;5790.913136303043;5797.449242321973;5799.768009279582;5803.680797556175;5737.885242295214;5803.303603567599;5826.213461182856;5827.3007239131575;5838.649681019152;5531.944680553304;5606.6916319602815;5614.185943038155;5676.943230567642;5701.4298570142655;5777.16231146473;5784.161296612052;5786.334571524329;5806.158107074366;5811.767529298781;5446.074167391632;5482.17767334903;5672.2342483765815;5675.943240567542;5678.4605462480995 03:49:40 INFO - PROCESS | 9067 | |1;ext.html;563.7716724726484;592.2212290837548;604.5194070713782;623.7224435126369;631.003015235927;35320;38180.61819381805;38537.807310963406;38606.4209036864;48907.26639100417;49437;49701.25448118272;49707;49716.254256186185;49747;9332.62669493221;9436.480993545387;9439.81120377592;9450.291228157881;9498.715038548855;8592.527410992392;8722.912770872274;9265.258779297674;9370.906290937106;11121.27711698741;11053.28153670009;11445.141614378914;11455.369954652533;11455.770884582345;11464.598739044135;11285.605003824869;11345.829812552818;11350.829737553944;11363.147763917657;11367.147463940155;11082.05802506788;11164;11168.832467512993;11171;11171.162162837738;46042;46349.07301853978;46391;46443.53556464426;46446;3799.1262009737916;3815.67566756826;3827.3302172119766;3842.562237984532;3944.2505923874373;34854.65145348539;34999.12502187466;35224.29551408957;35520.46719299187;36653.26693466116;4554.977225113887;4653.930191047137;4766.571008609207;4766.833160839354;4827.275908613701;393274;394232;394663;395566;397192.9999999971;39668;40872.38691419631;41055.178896422505;66871.66564167148;67258.66370668115;41452.79273603613;41623;42043.7897810509;42075.36886946698;42093.78953105215;16974.40589579359;17192.914035429993;17194;17203.741943870853;17215.311387544607;14413.42346306157;14521.419143234363;14541.927290363481;14555.272236388138;14559.85440145585;9161.221296189899;9311.627534898527;9526.237900967906;9695.418274903528;9746.707598772047;3294.341131773655;3303.7357011439026;3311.602607687045;3373.2916087621875;3384.966150338465;1047.4291511126303;1067.4662668665667;1069.1072954083427;1101.3391964821203;1153.429052618959;34724.87186256319;35003.82498087493;35297.470537941954;35585.28829423397;35693;1167.2121318110412;1180.138498895811;1228.4963165102265;1231.901447884167;1254.1158483269273;18434.723479147822;18488.815111848708;18538.165782539818;18723.25106995705;18776.906115469334;262733;265525;265630;265783;266009;66404;66715.66642166759;66827.33172668309;66842.33157668362;66953.33046669568;1442.077070674769;1469.5150600301874;1478.7633978563379;1482.4366740638488;1485.6137404274966;33975.66024339824;35130.82434587811;35234.8238258807;35277.647223527434;35609;56842.715786420806;57182.142267866846;57258.14112788395;57370.713146434;57450.42549574451;481876;483538;484078;484925;485518.151683168;31798.523022154222;32296.515552266264;32519;32587;32942.505862411606;40540.59459405486;44331;50576;52807.735961319944;57197.42802571921;44542.77728611336;45190;45210.547894520634;45498.31752523649;46391;43918.56081439145;45338.773306133255;45802;46450.76774616105;47003.52996469991;44846;45738.77130614326;45763.31355029611;46330.30504542367;46599;37690;39981;40287.79856100701;40955.59044409518;41183.382249265684;30426.84786576053;30551.213817936743;30785.538216927212;30912.845435772677;31016.379672405976 03:49:40 INFO - PROCESS | 9067 | |2;jquery.html;398.4521283235551;401.43999121225875;425.69775459423806;433.53828172995765;438.0406908869575;18993.95533245671;19765.901170494144;20504.384868453933;20748;20777.376678699646;19892;19907.800921990776;20238.29165979191;20326.69509957348;20419.28532501363;5839.970800145993;6117.051856962178;6120.510359171256;6123.326434092269;6128.877422451548;5896.911546326808;5937.1984782054515;5996.940030599681;6030.788922387717;6114.480269177126;5920.348761636216;5931.021381472052;5945;5950.55370847186;5981.88036239275;5761.4814666679995;5968.343482216953;5983.341832398411;6044.063170208625;6046.909296360558;5432.864178395532;5433.8369848904595;5434.755436005389;5438.293021907127;5438.592105592096;21951.341459756233;23697.644535331983;23704.40738981522;23898.16356427534;24039.27882163538;2495.5508008558454;2542.9872850635816;2545.312765553309;2554.961675574868;2604.9869750651314;15213.695726085527;15230.238488075553;15471.922640386727;15586;15864.444744434004;1941.5243265400068;1974.775639103765;2030.0661695620033;2061.752589689232;2063.2881655828714;84671.57664211701;86058.56970715169;86746;87353.56323218407;90361.54819225927;11101.666949991513;11311.151663625262;11380;11461.770764584662;11479.655610331703;16351.83648163527;16370.836291636931;16376.754348684779;16381.672366552602;16392.83607163937;8628.180322869317;8767.693130740394;8800.95599522011;8844.248238899765;8958.148975847284;8585.78535536604;8604.48373097616;8662.523561204194;8831.55842207887;8843.823123537622;6892.793216203522;6943.652817359111;6962.3733863951475;7007.824804379829;7091.617134658795;2050.1286953044896;2060.7527096748186;2071.140476702182;2081.344376521391;2094.6439105351824;1022.1516141602452;1033.8242498775226;1034.9379037257788;1093.3931667924219;1130.739929816147;20550.897245513675;20612.38162855087;21043.68434473484;21145.78854211469;21224.151033958777;1078.7464945737745;1106.994465027686;1128.836318733779;1152.8040233160216;1162.988370116288;10991.175661825395;11735.061195104372;11992.160548761678;12038.939805300917;12119.454624541917;522524;533192;533299;534503;535354;83288;84502;85005;85860;86991;1086.5436516663021;1088.1784252889177;1107.5680484611007;1110.228391268061;1112.67732357617;17950;18325.3586124485;18582.96769046637;18761.90619046896;19082.80917190866;40070.398944016444;40676;41314.1737165249;41709.1658166841;42629;53814;54181.45818541764;54386.45613543972;55088.44911550994;56407;6309.716062777278;8833.823323533365;9027;9172.35793494449;9344.065593440602;7735.265149810759;12014.699632509257;12522.436490358137;12876.678083047998;13616.455341786217;11965.222260553037;12135.939320303341;12179.330136842558;12289.201201921847;12465.750684986431;11569.19015668895;11897.940510297392;11948;12190.268583884996;12210.633680989584;11116.666500005012;11548.191626586058;11559.537618495502;12538.122331436713;12679.556215532411;9055.818883622422;9175.220106290773;9303.581338839904;9345.626174953197;9620.230381569598;11894.762104758029;12110.75778484443;12189.27256443399;12262.080343974416;12568.748625027265 03:49:40 INFO - PROCESS | 9067 | |3;mootools.html;1345.394572442401;1481.6369989352606;1508.3966413434625;1677.3961373905393;1821.6812057889865;7517.661705223264;8430.114837942012;8681.956590217056;8686.47881127132;8963.641454341834;8889.95555022224;8901.510416927067;8917.598708058136;8929.642814287437;8994.280457563396;2427.4052857050046;2449.4121410861444;2452.791512721412;2456.3981824453035;2459.8155138364605;2391.5814732421845;2409.855408675476;2446.9755302446924;2451.6199989001734;2453.3498622864868;2477.4797292568583;2481.04479775286;2486.490269494758;2500.8874600643016;2516.1822407717473;2386.4126046965384;2400.6759087523137;2411.7105947286263;2412.0713525292713;2426.587480128365;2297.1041293895228;2363.1492662641435;2407.2296865003204;2419.4919066995953;2421.673074134987;7556.622168891588;7767.766966990999;8888.911110888874;8992.8651070234;8998.505082220476;5058.735316170957;5225.399079105876;5233.031889100528;5287.47125287472;5302.204669299597;3462.8787992420143;3468.479728040789;3512.4906888501278;3517.7009954153928;3547.3260080584864;1154.2958795134962;1166.4867458318313;1187.9049676025897;1202.7774861650535;1203.0616119426772;12162.331071791048;12254.387280636021;12260.75478490434;12272.447739851728;12279.631611051684;4485.304777759453;4732.692374995614;4772.976135119302;4818.927716084261;4841.370621819141;10431.37411755297;10451.68644940653;10483.37099774016;10485.318454300446;10493.790124197474;5171.663841850268;5219.373675159005;5244.737763111829;5248.685078895202;5253.290805741252;4736.384270044872;4788.449328327218;4792.712437253706;4792.904141917212;4793.113274044313;4091.97954010234;4118.608732170439;4144.979275103666;4197.748135111842;4197.9370309445385;1317.5981325695755;1328.3291937571464;1329.7938819482995;1346.104840281202;1357.138217232045;943.5895385507326;964.3876138652007;1030.7114008077751;1039.095986491742;1069.9304545204536;5828.413892301756;5904.793332233351;5931.317898441648;6037.396260373927;6061.212042434543;504.3393154967005;524.0880867290887;531.5481840435599;545.3728212555513;545.6671430427436;3999.5200575931076;4028.637422631977;4072.490938632671;4106.281400754856;4114.485689288839;9457.668981585612;9796.755081122887;10215.489225538842;10498.160147188206;10652.786944261072;9519.095685909828;9882.357646752938;10825.07986821129;10863.293885897392;10865.674029779118;7401.407887368998;7541.924580754232;7761.72833950809;7979.122296547375;8052.59737013147;5830.18377427169;5881.206037184926;5929.881402371842;5996.409691848476;5998.430149135826;9600.917888855744;9794.853077203705;9996.700098997042;10049.145822604867;10067.999999999707;9847.901520984698;9902.257330700082;10064.295499314978;10118.93751156118;10154.390736555535;7729.6521656524465;7814.296713295831;7815.101263354787;7821.452498325063;7824.921750782647;8299.626516806878;8359.540225287668;8381.413301068867;8425.789355266168;8500.489970601537;7308.671109800178;7512.849743005218;7569.5079819811535;7716.652750626123;7717.57553334572;7456.366208872089;7530.849383012419;7552.320291173821;7610.581418022062;7668.884966725615;6940.236573976757;6962.895556566757;7001.159860816734;7066.646667666699;7076.929230707627;5137.17805151174;5180.818671346485;5277.736113194248;5279.049771041249;5290.312259406252;5055.115354812967;5126.384833819965;5213.947860521498;5279.868003299948;5280.947190528045 03:49:40 INFO - PROCESS | 9067 | |4;prototype.html;336.1344537815125;349.2299479647377;350.8754392190774;351.59566498526675;352.94705794130886;5296.020236256295;5439.891202175964;5490.972545137273;5507.972460137704;5524.1989911462815;5215.191645294976;5355.250264962909;5365.973170134153;5376.569874410047;5380.7309634518315;4300.22595932732;4304.741715497064;4306.18182545317;4311.611954924057;4312.482502099745;4229.450171477703;4275.828966841323;4311.525732169459;4313.935290970638;4315.935260971088;4252.255855225323;4259.382389553512;4275.679324050693;4279.1441711657635;4286.5284818669925;4258.638015768633;4267.786610669454;4269.7011209215525;4280.486341638992;4283.550227226127;3726.441033844918;3811.294910441577;3817.0723784488932;3817.2556351511466;3840.7133610240603;5229.980153869997;5250.396204436501;5262.394824595182;5263.578913686896;5275.815346462856;4873.731944743037;4892.559669629715;4893;4900.754962251909;4904.362432883738;5396.379416367129;5582;5676.858078548028;5679.801206957736;5679.943200567983;4121.278776214181;4161.479815023122;4164.5845409662625;4166.7708276044805;4167.895802604929;5844.5616578756335;5860.736266868002;5864.853378665525;5882.823515294548;5909.409059094142;4189.790510474464;4243.130158317537;4271.462273581508;4275.422817919603;4294.957050429518;4329.19909816685;4335.523092459827;4345.500267469281;4366.868993930188;4380.6933514653665;4899.314096026559;4899.681520701143;4918.589890953407;4922.803087876445;4929.260610908321;5487.941128275423;5723.227364305849;5725.4274572543245;5729.541636669056;5735.8852822944145;1485.4281101775894;1507.3970411835353;1510.2901636230954;1513.137511618367;1519.620094976256;939.7744541310036;965.9661911833052;983.6409710455632;985.5762022330467;990.2078337330107;4794.089123066607;4861.586765124934;4880.292357608127;4901.926471102936;4911.410630724265;5090.36039231951;5110.565601923878;5124.359455068116;5143.5113664201845;5158.252053452228;4642.908916404642;4900.240462728283;4905.583025442877;4922.458529561745;4944.703317800943;2713.91858244253;2725.2286014091;2727.618133461317;2728.808983371145;2731.0063653533034;5235.895282094337;5241.449647787003;5249.737513124405;5254.894902101936;5257.5005374489365;4818.204996175662;5148.330717006766;5158.613104017214;5181.3730388728045;5205.282256855228;5194.818181363709;5244.265802787613;5247.239150323258;5262.42113367537;5266.8419947401635;5047.192449208109;5068.873278168076;5111.744412779271;5118.488151184852;5121.897562048813;4713.599344055657;4968.900621987612;4969.751512424292;4975.477574854588;5055.898882022266;5170.819021334235;5181.689098654093;5208.374995000624;5211.921821172609;5223.973880130575;3831.6743076837665;4110.547839737566;4212.157568486254;4235.682323825664;4312.417823593774;5835.854103647443;5837.854053648693;5842.474177324061;5846.41535846412;5850.502707269759;5563.859408821101;5720.799772007959;5721.6280941740315;5723.6851973141875;5723.742431590672;5669.262995810518;5674.233978413026;5684.7157642118555;5689.800856969986;5694.943050569607;5631.352394474688;5631.465010823965;5660.575456840671;5664.348599911063;5669.177969194526;5522.597670013557;5719.628224165416;5749.453801888814;5760.049591817303;5784.595078344475;5582.581306401955;5598.524125449382;5609.663420194802;5618.157276408571;5645.745941432563 03:49:40 INFO - PROCESS | 9067 | |5;yui.html;806.4274365200707;812.2841906344919;827.6978902700516;872.3326655108839;893.200585475999;15425.614359641;16440.506784796446;16520.0913949733;16542.917285413572;16555.999999999985;15739;15974.281157347918;16009.439669611565;16023.27895244714;16026.5993350166;706.8798304288258;724.4856152131993;840.7099550655045;871.6905498548019;873.4846440600077;874.2874557235832;875.544716747291;875.6847534887468;875.7941883657368;877.5261358866204;922.3589605224372;922.6678395777515;923.6305477808896;924.6393906376519;924.8150369926008;752.725255281821;771.3906014248737;877.2368039805347;902.3908861518514;917.0966597901062;666.7233098264214;670.4133882852504;670.4770279182246;670.7451168555966;671.4326394196945;15268.083914965138;15294.847051529454;15297.770533442008;15320.387184512607;15341.232938353149;13873;13956.302184890716;14022.719545609132;14091.647708807363;14326;12094.274343539328;12224.816627750592;12357.567485138066;12403.503859845596;12680.55618053373;4549.818007279705;4576.26779715244;4606.631469482433;4697.459792123916;4809.95190048102;83246;87082;87646.5617671914;87648.99999999936;88645.11354886433;11270.718232044264;11437.199396042197;11438.370889601152;11493.367864767519;11697.473613687405;21369.145234190768;21438.678419823715;21537;21813.563728725338;22300.776992230196;8187.222213889671;8227.177282271725;8354.791130221793;8430.11483794205;8487.405881588229;4704.623630109583;4795.568398844121;4802.375691160127;4860.8298709545;4928.260760885824;3421.0934102462866;3491.4762785582366;3542.1852973816167;3549.94675079874;3553.022918697379;569.2343797592232;571.2573654249542;573.1402895656515;573.730346737033;574.6293640601789;621.3134486392471;637.7480895046524;672.3982036077683;715.1346870286907;726.5967388099656;9004.59479323432;9152.54237288133;9188.8621670675;9415.340926135243;9476.573554190078;675.3853992866468;690.1614538335946;695.5339922252103;729.4565548666171;782.0419985517696;4497.662675299301;4575.58819706228;4581;4659.813607455664;4669.719816811003;77327.61336193395;126780;129430.35284823515;138253;138503;79321;82348;112308.43845780719;114086;115324;87953;89827.55086224528;91189;91290.0870991295;92248;8896.332775042027;8939.553022348986;8951.820963580562;8998.955005224932;9004.50475223869;59814;60162.42284914631;60163.699181505566;60550;63094;76102.23897760952;76817.61591192009;86119.56940215259;86404.5679771597;89811.55094224487;12533.811992820298;12832.78492372091;12970.80543791825;12984.15602985803;13294.867051329364;13082.869171308164;13141;13444;13474.663133421744;13504.797428038784;10885.23803333759;11104;11497.712557186138;11513.884861151617;11801.586944457245;12617.52147336529;12638.2417054977;12711.682207944876;12725.172863763826;12925.224486530838;12043.81934270969;12752.553660621834;12868;12882.09825312219;13030.804537931748;9861.260405469762;10011.449370284705;10046.296759226783;10211.795764084529;10383.792324153626;10185.33795303303;10314.226433017404;10482.648583964716;10638.308509946828;10677.733056673645 03:49:40 INFO - PROCESS | 9067 | __end_tp_report 03:49:40 INFO - PROCESS | 9067 | __start_cc_report 03:49:40 INFO - PROCESS | 9067 | _x_x_mozilla_cycle_collect,855 03:49:40 INFO - PROCESS | 9067 | __end_cc_report 03:49:40 INFO - PROCESS | 9067 | __startTimestamp1479124180277__endTimestamp 03:49:40 INFO - PROCESS | 9067 | 03:49:40 INFO - PROCESS | 9067 | ------- Summary: start ------- 03:49:40 INFO - PROCESS | 9067 | Number of tests: 6 03:49:40 INFO - PROCESS | 9067 | 03:49:40 INFO - PROCESS | 9067 | [#0] dojo.html Cycles:165 Average:5438.81 Median:5678.46 stddev:617.38 (10.9%) stddev-sans-first:591.22 03:49:40 INFO - PROCESS | 9067 | Values: 3093.0 3129.8 3135.6 3163.0 3171.4 5431.2 5492.1 5503.0 5510.2 5521.1 5467.5 5502.4 5504.3 5505.2 5505.4 4967.5 4987.6 5013.3 5050.9 5082.6 4928.2 5021.8 5079.0 5081.0 5104.5 4902.9 4911.8 4933.6 4980.9 4982.0 4955.2 4977.8 4979.6 5026.6 5048.9 4912.9 4918.5 4992.4 5008.2 5019.8 5179.4 5288.7 5301.6 5405.7 5443.3 5659.8 5712.3 5715.3 5791.2 5799.0 5829.7 5835.3 5837.7 5841.7 5843.5 5267.6 5310.5 5378.9 5425.1 5434.8 5945.2 5951.2 5970.0 5970.0 5984.4 5731.3 5750.2 5750.7 5753.2 5756.8 5706.0 5764.2 5776.9 5814.4 5833.9 5867.7 5883.6 5884.9 5889.1 5907.2 5779.5 5840.9 5846.6 5852.2 5863.9 4314.8 4359.7 4438.1 4491.8 4506.9 4009.3 4033.6 4060.4 4061.1 4068.3 5665.9 5677.3 5681.7 5686.4 5688.2 5698.6 5719.5 5756.4 5925.2 5933.9 5525.8 5581.2 5584.9 5612.6 5617.7 5104.8 5109.8 5113.7 5114.7 5139.1 5800.6 5903.6 6036.0 6042.1 6051.8 5836.3 5881.4 5900.6 5938.0 5939.3 6133.3 6134.5 6138.6 6138.8 6147.2 5570.5 5829.2 5829.9 5832.2 5832.9 5712.5 5847.3 5889.9 5890.9 5968.8 5740.7 5790.9 5797.4 5799.8 5803.7 5737.9 5803.3 5826.2 5827.3 5838.6 5531.9 5606.7 5614.2 5676.9 5701.4 5777.2 5784.2 5786.3 5806.2 5811.8 5446.1 5482.2 5672.2 5675.9 5678.5 03:49:40 INFO - PROCESS | 9067 | 03:49:40 INFO - PROCESS | 9067 | [#1] ext.html Cycles:180 Average:56130.12 Median:393753.00 stddev:103461.47 (26.3%) stddev-sans-first:103667.59 03:49:40 INFO - PROCESS | 9067 | Values: 563.8 592.2 604.5 623.7 631.0 35320.0 38180.6 38537.8 38606.4 48907.3 49437.0 49701.3 49707.0 49716.3 49747.0 9332.6 9436.5 9439.8 9450.3 9498.7 8592.5 8722.9 9265.3 9370.9 11121.3 11053.3 11445.1 11455.4 11455.8 11464.6 11285.6 11345.8 11350.8 11363.1 11367.1 11082.1 11164.0 11168.8 11171.0 11171.2 46042.0 46349.1 46391.0 46443.5 46446.0 3799.1 3815.7 3827.3 3842.6 3944.3 34854.7 34999.1 35224.3 35520.5 36653.3 4555.0 4653.9 4766.6 4766.8 4827.3 393274.0 394232.0 394663.0 395566.0 397193.0 39668.0 40872.4 41055.2 66871.7 67258.7 41452.8 41623.0 42043.8 42075.4 42093.8 16974.4 17192.9 17194.0 17203.7 17215.3 14413.4 14521.4 14541.9 14555.3 14559.9 9161.2 9311.6 9526.2 9695.4 9746.7 3294.3 3303.7 3311.6 3373.3 3385.0 1047.4 1067.5 1069.1 1101.3 1153.4 34724.9 35003.8 35297.5 35585.3 35693.0 1167.2 1180.1 1228.5 1231.9 1254.1 18434.7 18488.8 18538.2 18723.3 18776.9 262733.0 265525.0 265630.0 265783.0 266009.0 66404.0 66715.7 66827.3 66842.3 66953.3 1442.1 1469.5 1478.8 1482.4 1485.6 33975.7 35130.8 35234.8 35277.6 35609.0 56842.7 57182.1 57258.1 57370.7 57450.4 481876.0 483538.0 484078.0 484925.0 485518.2 31798.5 32296.5 32519.0 32587.0 32942.5 40540.6 44331.0 50576.0 52807.7 57197.4 44542.8 45190.0 45210.5 45498.3 46391.0 43918.6 45338.8 45802.0 46450.8 47003.5 44846.0 45738.8 45763.3 46330.3 46599.0 37690.0 39981.0 40287.8 40955.6 41183.4 30426.8 30551.2 30785.5 30912.8 31016.4 03:49:40 INFO - PROCESS | 9067 | 03:49:40 INFO - PROCESS | 9067 | [#2] jquery.html Cycles:180 Average:30563.43 Median:23701.03 stddev:87311.15 (368.4%) stddev-sans-first:87526.70 03:49:40 INFO - PROCESS | 9067 | Values: 398.5 401.4 425.7 433.5 438.0 18994.0 19765.9 20504.4 20748.0 20777.4 19892.0 19907.8 20238.3 20326.7 20419.3 5840.0 6117.1 6120.5 6123.3 6128.9 5896.9 5937.2 5996.9 6030.8 6114.5 5920.3 5931.0 5945.0 5950.6 5981.9 5761.5 5968.3 5983.3 6044.1 6046.9 5432.9 5433.8 5434.8 5438.3 5438.6 21951.3 23697.6 23704.4 23898.2 24039.3 2495.6 2543.0 2545.3 2555.0 2605.0 15213.7 15230.2 15471.9 15586.0 15864.4 1941.5 1974.8 2030.1 2061.8 2063.3 84671.6 86058.6 86746.0 87353.6 90361.5 11101.7 11311.2 11380.0 11461.8 11479.7 16351.8 16370.8 16376.8 16381.7 16392.8 8628.2 8767.7 8801.0 8844.2 8958.1 8585.8 8604.5 8662.5 8831.6 8843.8 6892.8 6943.7 6962.4 7007.8 7091.6 2050.1 2060.8 2071.1 2081.3 2094.6 1022.2 1033.8 1034.9 1093.4 1130.7 20550.9 20612.4 21043.7 21145.8 21224.2 1078.7 1107.0 1128.8 1152.8 1163.0 10991.2 11735.1 11992.2 12038.9 12119.5 522524.0 533192.0 533299.0 534503.0 535354.0 83288.0 84502.0 85005.0 85860.0 86991.0 1086.5 1088.2 1107.6 1110.2 1112.7 17950.0 18325.4 18583.0 18761.9 19082.8 40070.4 40676.0 41314.2 41709.2 42629.0 53814.0 54181.5 54386.5 55088.4 56407.0 6309.7 8833.8 9027.0 9172.4 9344.1 7735.3 12014.7 12522.4 12876.7 13616.5 11965.2 12135.9 12179.3 12289.2 12465.8 11569.2 11897.9 11948.0 12190.3 12210.6 11116.7 11548.2 11559.5 12538.1 12679.6 9055.8 9175.2 9303.6 9345.6 9620.2 11894.8 12110.8 12189.3 12262.1 12568.7 03:49:40 INFO - PROCESS | 9067 | 03:49:40 INFO - PROCESS | 9067 | [#3] mootools.html Cycles:180 Average:5745.02 Median:5056.93 stddev:3198.56 (63.3%) stddev-sans-first:3190.44 03:49:40 INFO - PROCESS | 9067 | Values: 1345.4 1481.6 1508.4 1677.4 1821.7 7517.7 8430.1 8682.0 8686.5 8963.6 8890.0 8901.5 8917.6 8929.6 8994.3 2427.4 2449.4 2452.8 2456.4 2459.8 2391.6 2409.9 2447.0 2451.6 2453.3 2477.5 2481.0 2486.5 2500.9 2516.2 2386.4 2400.7 2411.7 2412.1 2426.6 2297.1 2363.1 2407.2 2419.5 2421.7 7556.6 7767.8 8888.9 8992.9 8998.5 5058.7 5225.4 5233.0 5287.5 5302.2 3462.9 3468.5 3512.5 3517.7 3547.3 1154.3 1166.5 1187.9 1202.8 1203.1 12162.3 12254.4 12260.8 12272.4 12279.6 4485.3 4732.7 4773.0 4818.9 4841.4 10431.4 10451.7 10483.4 10485.3 10493.8 5171.7 5219.4 5244.7 5248.7 5253.3 4736.4 4788.4 4792.7 4792.9 4793.1 4092.0 4118.6 4145.0 4197.7 4197.9 1317.6 1328.3 1329.8 1346.1 1357.1 943.6 964.4 1030.7 1039.1 1069.9 5828.4 5904.8 5931.3 6037.4 6061.2 504.3 524.1 531.5 545.4 545.7 3999.5 4028.6 4072.5 4106.3 4114.5 9457.7 9796.8 10215.5 10498.2 10652.8 9519.1 9882.4 10825.1 10863.3 10865.7 7401.4 7541.9 7761.7 7979.1 8052.6 5830.2 5881.2 5929.9 5996.4 5998.4 9600.9 9794.9 9996.7 10049.1 10068.0 9847.9 9902.3 10064.3 10118.9 10154.4 7729.7 7814.3 7815.1 7821.5 7824.9 8299.6 8359.5 8381.4 8425.8 8500.5 7308.7 7512.8 7569.5 7716.7 7717.6 7456.4 7530.8 7552.3 7610.6 7668.9 6940.2 6962.9 7001.2 7066.6 7076.9 5137.2 5180.8 5277.7 5279.0 5290.3 5055.1 5126.4 5213.9 5279.9 5280.9 03:49:40 INFO - PROCESS | 9067 | 03:49:40 INFO - PROCESS | 9067 | [#4] prototype.html Cycles:180 Average:4618.76 Median:5115.12 stddev:1307.62 (25.6%) stddev-sans-first:1271.16 03:49:40 INFO - PROCESS | 9067 | Values: 336.1 349.2 350.9 351.6 352.9 5296.0 5439.9 5491.0 5508.0 5524.2 5215.2 5355.3 5366.0 5376.6 5380.7 4300.2 4304.7 4306.2 4311.6 4312.5 4229.5 4275.8 4311.5 4313.9 4315.9 4252.3 4259.4 4275.7 4279.1 4286.5 4258.6 4267.8 4269.7 4280.5 4283.6 3726.4 3811.3 3817.1 3817.3 3840.7 5230.0 5250.4 5262.4 5263.6 5275.8 4873.7 4892.6 4893.0 4900.8 4904.4 5396.4 5582.0 5676.9 5679.8 5679.9 4121.3 4161.5 4164.6 4166.8 4167.9 5844.6 5860.7 5864.9 5882.8 5909.4 4189.8 4243.1 4271.5 4275.4 4295.0 4329.2 4335.5 4345.5 4366.9 4380.7 4899.3 4899.7 4918.6 4922.8 4929.3 5487.9 5723.2 5725.4 5729.5 5735.9 1485.4 1507.4 1510.3 1513.1 1519.6 939.8 966.0 983.6 985.6 990.2 4794.1 4861.6 4880.3 4901.9 4911.4 5090.4 5110.6 5124.4 5143.5 5158.3 4642.9 4900.2 4905.6 4922.5 4944.7 2713.9 2725.2 2727.6 2728.8 2731.0 5235.9 5241.4 5249.7 5254.9 5257.5 4818.2 5148.3 5158.6 5181.4 5205.3 5194.8 5244.3 5247.2 5262.4 5266.8 5047.2 5068.9 5111.7 5118.5 5121.9 4713.6 4968.9 4969.8 4975.5 5055.9 5170.8 5181.7 5208.4 5211.9 5224.0 3831.7 4110.5 4212.2 4235.7 4312.4 5835.9 5837.9 5842.5 5846.4 5850.5 5563.9 5720.8 5721.6 5723.7 5723.7 5669.3 5674.2 5684.7 5689.8 5694.9 5631.4 5631.5 5660.6 5664.3 5669.2 5522.6 5719.6 5749.5 5760.0 5784.6 5582.6 5598.5 5609.7 5618.2 5645.7 03:49:40 INFO - PROCESS | 9067 | 03:49:40 INFO - PROCESS | 9067 | [#5] yui.html Cycles:180 Average:21824.29 Median:4750.10 stddev:32609.88 (686.5%) stddev-sans-first:32663.17 03:49:40 INFO - PROCESS | 9067 | Values: 806.4 812.3 827.7 872.3 893.2 15425.6 16440.5 16520.1 16542.9 16556.0 15739.0 15974.3 16009.4 16023.3 16026.6 706.9 724.5 840.7 871.7 873.5 874.3 875.5 875.7 875.8 877.5 922.4 922.7 923.6 924.6 924.8 752.7 771.4 877.2 902.4 917.1 666.7 670.4 670.5 670.7 671.4 15268.1 15294.8 15297.8 15320.4 15341.2 13873.0 13956.3 14022.7 14091.6 14326.0 12094.3 12224.8 12357.6 12403.5 12680.6 4549.8 4576.3 4606.6 4697.5 4810.0 83246.0 87082.0 87646.6 87649.0 88645.1 11270.7 11437.2 11438.4 11493.4 11697.5 21369.1 21438.7 21537.0 21813.6 22300.8 8187.2 8227.2 8354.8 8430.1 8487.4 4704.6 4795.6 4802.4 4860.8 4928.3 3421.1 3491.5 3542.2 3549.9 3553.0 569.2 571.3 573.1 573.7 574.6 621.3 637.7 672.4 715.1 726.6 9004.6 9152.5 9188.9 9415.3 9476.6 675.4 690.2 695.5 729.5 782.0 4497.7 4575.6 4581.0 4659.8 4669.7 77327.6 126780.0 129430.4 138253.0 138503.0 79321.0 82348.0 112308.4 114086.0 115324.0 87953.0 89827.6 91189.0 91290.1 92248.0 8896.3 8939.6 8951.8 8999.0 9004.5 59814.0 60162.4 60163.7 60550.0 63094.0 76102.2 76817.6 86119.6 86404.6 89811.6 12533.8 12832.8 12970.8 12984.2 13294.9 13082.9 13141.0 13444.0 13474.7 13504.8 10885.2 11104.0 11497.7 11513.9 11801.6 12617.5 12638.2 12711.7 12725.2 12925.2 12043.8 12752.6 12868.0 12882.1 13030.8 9861.3 10011.4 10046.3 10211.8 10383.8 10185.3 10314.2 10482.6 10638.3 10677.7 03:49:40 INFO - PROCESS | 9067 | -------- Summary: end -------- 03:49:40 INFO - PROCESS | 9067 | 03:49:41 INFO - TEST-INFO | 9067: exit 0 03:49:41 INFO - TEST-OK | dromaeo_css | took 1114696ms 03:49:41 INFO - TEST-START | kraken 03:49:41 INFO - operating with platform_type : linux_ 03:49:41 INFO - Initialising browser for kraken test... 03:49:41 INFO - TEST-INFO | started process 9386 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_fgYdk/profile http://localhost:34917/getInfo.html) 03:49:45 INFO - PROCESS | 9386 | __metrics Screen width/height:1600/1200 03:49:45 INFO - PROCESS | 9386 | colorDepth:24 03:49:45 INFO - PROCESS | 9386 | Browser inner width/height: 1024/697 03:49:45 INFO - PROCESS | 9386 | __metrics 03:49:46 INFO - PROCESS | 9386 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:49:46 INFO - PROCESS | 9386 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:49:47 INFO - PROCESS | 9386 | console.error: PushService: 03:49:47 INFO - PROCESS | 9386 | stateChangeProcessEnqueue: Error transitioning state 03:49:47 INFO - PROCESS | 9386 | UnknownError 03:49:47 INFO - TEST-INFO | 9386: exit 0 03:49:47 INFO - Browser initialized. 03:49:47 INFO - Running cycle 1/1 for kraken test... 03:49:47 INFO - TEST-INFO | started process 9478 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_fgYdk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:49:59 INFO - PROCESS | 9478 | RSS: Main: 144609280 03:49:59 INFO - PROCESS | 9478 | 03:50:02 INFO - PROCESS | 9478 | Cycle 1(1): loaded http://localhost:34917/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34917/tests/kraken/driver.html?testName=audio-beat-detection) 03:50:02 INFO - PROCESS | 9478 | RSS: Main: 186945536 03:50:02 INFO - PROCESS | 9478 | 03:50:05 INFO - PROCESS | 9478 | Cycle 1(1): loaded http://localhost:34917/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34917/tests/kraken/driver.html?testName=audio-dft) 03:50:06 INFO - PROCESS | 9478 | RSS: Main: 176463872 03:50:06 INFO - PROCESS | 9478 | 03:50:08 INFO - PROCESS | 9478 | Cycle 1(1): loaded http://localhost:34917/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34917/tests/kraken/driver.html?testName=audio-fft) 03:50:09 INFO - PROCESS | 9478 | RSS: Main: 267448320 03:50:09 INFO - PROCESS | 9478 | 03:50:11 INFO - PROCESS | 9478 | Cycle 1(1): loaded http://localhost:34917/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34917/tests/kraken/driver.html?testName=audio-oscillator) 03:50:12 INFO - PROCESS | 9478 | RSS: Main: 173891584 03:50:12 INFO - PROCESS | 9478 | 03:50:14 INFO - PROCESS | 9478 | Cycle 1(1): loaded http://localhost:34917/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34917/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:50:14 INFO - PROCESS | 9478 | RSS: Main: 175521792 03:50:14 INFO - PROCESS | 9478 | 03:50:17 INFO - PROCESS | 9478 | Cycle 1(1): loaded http://localhost:34917/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34917/tests/kraken/driver.html?testName=imaging-darkroom) 03:50:18 INFO - PROCESS | 9478 | RSS: Main: 211394560 03:50:18 INFO - PROCESS | 9478 | 03:50:21 INFO - PROCESS | 9478 | Cycle 1(1): loaded http://localhost:34917/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34917/tests/kraken/driver.html?testName=imaging-desaturate) 03:50:22 INFO - PROCESS | 9478 | RSS: Main: 210444288 03:50:22 INFO - PROCESS | 9478 | 03:50:25 INFO - PROCESS | 9478 | Cycle 1(1): loaded http://localhost:34917/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34917/tests/kraken/driver.html?testName=json-parse-financial) 03:50:25 INFO - PROCESS | 9478 | RSS: Main: 210259968 03:50:25 INFO - PROCESS | 9478 | 03:50:27 INFO - PROCESS | 9478 | Cycle 1(1): loaded http://localhost:34917/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34917/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:50:27 INFO - PROCESS | 9478 | RSS: Main: 172056576 03:50:27 INFO - PROCESS | 9478 | 03:50:29 INFO - PROCESS | 9478 | Cycle 1(1): loaded http://localhost:34917/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34917/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:50:30 INFO - PROCESS | 9478 | RSS: Main: 179335168 03:50:30 INFO - PROCESS | 9478 | 03:50:32 INFO - PROCESS | 9478 | Cycle 1(1): loaded http://localhost:34917/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34917/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:50:32 INFO - PROCESS | 9478 | RSS: Main: 188825600 03:50:32 INFO - PROCESS | 9478 | 03:50:35 INFO - PROCESS | 9478 | Cycle 1(1): loaded http://localhost:34917/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34917/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:50:35 INFO - PROCESS | 9478 | RSS: Main: 197353472 03:50:35 INFO - PROCESS | 9478 | 03:50:38 INFO - PROCESS | 9478 | Cycle 1(1): loaded http://localhost:34917/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34917/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:50:39 INFO - PROCESS | 9478 | RSS: Main: 193740800 03:50:39 INFO - PROCESS | 9478 | 03:50:40 INFO - PROCESS | 9478 | Cycle 1(1): loaded http://localhost:34917/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34917/tests/kraken/driver.html?testName=ai-astar) 03:50:41 INFO - PROCESS | 9478 | RSS: Main: 244592640 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | __start_tp_report 03:50:41 INFO - PROCESS | 9478 | _x_x_mozilla_page_load 03:50:41 INFO - PROCESS | 9478 | _x_x_mozilla_page_load_details 03:50:41 INFO - PROCESS | 9478 | |i|pagename|runs| 03:50:41 INFO - PROCESS | 9478 | |0;ai-astar;107;114;111;111;111;110;111;111;110;111 03:50:41 INFO - PROCESS | 9478 | |1;audio-beat-detection;140;116;121;162;118;117;119;119;145;116 03:50:41 INFO - PROCESS | 9478 | |2;audio-dft;160;160;161;160;161;160;161;161;161;161 03:50:41 INFO - PROCESS | 9478 | |3;audio-fft;74;75;75;111;75;75;76;75;97;76 03:50:41 INFO - PROCESS | 9478 | |4;audio-oscillator;82;82;124;82;82;82;100;83;82;113 03:50:41 INFO - PROCESS | 9478 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;116;117;117 03:50:41 INFO - PROCESS | 9478 | |6;imaging-darkroom;145;146;146;145;145;146;145;146;145;146 03:50:41 INFO - PROCESS | 9478 | |7;imaging-desaturate;95;95;95;95;95;95;94;95;95;95 03:50:41 INFO - PROCESS | 9478 | |8;json-parse-financial;49;48;50;49;50;50;50;49;49;49 03:50:41 INFO - PROCESS | 9478 | |9;json-stringify-tinderbox;59;58;58;59;59;58;58;63;59;59 03:50:41 INFO - PROCESS | 9478 | |10;stanford-crypto-aes;74;73;74;75;73;74;73;82;73;73 03:50:41 INFO - PROCESS | 9478 | |11;stanford-crypto-ccm;123;120;122;120;117;132;118;125;154;149 03:50:41 INFO - PROCESS | 9478 | |12;stanford-crypto-pbkdf2;150;152;154;155;160;156;152;157;149;151 03:50:41 INFO - PROCESS | 9478 | |13;stanford-crypto-sha256-iterative;63;62;63;61;61;59;62;59;60;60 03:50:41 INFO - PROCESS | 9478 | __end_tp_report 03:50:41 INFO - PROCESS | 9478 | __start_cc_report 03:50:41 INFO - PROCESS | 9478 | _x_x_mozilla_cycle_collect,1597 03:50:41 INFO - PROCESS | 9478 | __end_cc_report 03:50:41 INFO - PROCESS | 9478 | __startTimestamp1479124241027__endTimestamp 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | ------- Summary: start ------- 03:50:41 INFO - PROCESS | 9478 | Number of tests: 14 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:1.70 (1.5%) stddev-sans-first:1.17 03:50:41 INFO - PROCESS | 9478 | Values: 107.0 114.0 111.0 111.0 111.0 110.0 111.0 111.0 110.0 111.0 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:120.00 stddev:16.00 (13.3%) stddev-sans-first:16.30 03:50:41 INFO - PROCESS | 9478 | Values: 140.0 116.0 121.0 162.0 118.0 117.0 119.0 119.0 145.0 116.0 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.52 (0.3%) stddev-sans-first:0.50 03:50:41 INFO - PROCESS | 9478 | Values: 160.0 160.0 161.0 160.0 161.0 160.0 161.0 161.0 161.0 161.0 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.00 stddev:12.63 (16.8%) stddev-sans-first:13.14 03:50:41 INFO - PROCESS | 9478 | Values: 74.0 75.0 75.0 111.0 75.0 75.0 76.0 75.0 97.0 76.0 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | [#4] audio-oscillator Cycles:10 Average:91.20 Median:82.00 stddev:15.65 (19.1%) stddev-sans-first:16.24 03:50:41 INFO - PROCESS | 9478 | Values: 82.0 82.0 124.0 82.0 82.0 82.0 100.0 83.0 82.0 113.0 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 03:50:41 INFO - PROCESS | 9478 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 03:50:41 INFO - PROCESS | 9478 | Values: 145.0 146.0 146.0 145.0 145.0 146.0 145.0 146.0 145.0 146.0 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 03:50:41 INFO - PROCESS | 9478 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | [#8] json-parse-financial Cycles:10 Average:49.30 Median:49.50 stddev:0.67 (1.4%) stddev-sans-first:0.71 03:50:41 INFO - PROCESS | 9478 | Values: 49.0 48.0 50.0 49.0 50.0 50.0 50.0 49.0 49.0 49.0 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:1.49 (2.5%) stddev-sans-first:1.58 03:50:41 INFO - PROCESS | 9478 | Values: 59.0 58.0 58.0 59.0 59.0 58.0 58.0 63.0 59.0 59.0 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | [#10] stanford-crypto-aes Cycles:10 Average:74.40 Median:74.00 stddev:2.76 (3.7%) stddev-sans-first:2.92 03:50:41 INFO - PROCESS | 9478 | Values: 74.0 73.0 74.0 75.0 73.0 74.0 73.0 82.0 73.0 73.0 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:124.00 stddev:13.13 (10.6%) stddev-sans-first:13.80 03:50:41 INFO - PROCESS | 9478 | Values: 123.0 120.0 122.0 120.0 117.0 132.0 118.0 125.0 154.0 149.0 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.60 Median:154.50 stddev:3.44 (2.2%) stddev-sans-first:3.39 03:50:41 INFO - PROCESS | 9478 | Values: 150.0 152.0 154.0 155.0 160.0 156.0 152.0 157.0 149.0 151.0 03:50:41 INFO - PROCESS | 9478 | 03:50:41 INFO - PROCESS | 9478 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:61.50 stddev:1.49 (2.4%) stddev-sans-first:1.39 03:50:41 INFO - PROCESS | 9478 | Values: 63.0 62.0 63.0 61.0 61.0 59.0 62.0 59.0 60.0 60.0 03:50:41 INFO - PROCESS | 9478 | -------- Summary: end -------- 03:50:41 INFO - PROCESS | 9478 | 03:50:42 INFO - TEST-INFO | 9478: exit 0 03:50:42 INFO - TEST-OK | kraken | took 60924ms 03:50:42 INFO - SUITE-END | took 1175s 03:50:42 INFO - Completed test suite (00:19:35) 03:50:42 INFO - javascript benchmark 03:50:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3093.0, 3129.7809153359267, 3135.608048993877, 3162.968370316296, 3171.365726854628, 5431.185322201672, 5492.093804522254, 5503.036968530511, 5510.201020851984, 5521.116621340586, 5467.528458037463, 5502.422245664197, 5504.311961004874, 5505.201745746865, 5505.394406615279, 4967.503249675031, 4987.551120399164, 5013.298138260647, 5050.924236136461, 5082.64421490494, 4928.211486162215, 5021.84934451967, 5078.984203159364, 5081.034603425356, 5104.5405913467785, 4902.926456103161, 4911.82808601699, 4933.605311575084, 4980.9252861207115, 4982.00359928014, 4955.231939049435, 4977.776000079987, 4979.601631869442, 5026.623003274769, 5048.873778155576, 4912.926306105412, 4918.458969513351, 4992.350994370745, 5008.173651347523, 5019.774110165052, 5179.404368497596, 5288.68267903927, 5301.602379821529, 5405.702686352249, 5443.319585051869, 5659.773609055633, 5712.285964254468, 5715.3141623005085, 5791.160281759164, 5799.0, 5829.650220986754, 5835.328937172237, 5837.678927658977, 5841.7371218295275, 5843.53251739864, 5267.578593712494, 5310.468953104697, 5378.8924221515745, 5425.131978883439, 5434.782608695687, 5945.16767652529, 5951.226340575784, 5969.970150149308, 5970.0, 5984.431479009487, 5731.340895796953, 5750.1949727038245, 5750.654960702372, 5753.223314852525, 5756.798512052058, 5706.0, 5764.193012978187, 5776.855578610568, 5814.418558144236, 5833.941660583339, 5867.735951882176, 5883.646981181143, 5884.852878677982, 5889.057750759858, 5907.202527658711, 5779.537636989031, 5840.8610320987, 5846.649201047951, 5852.239208902815, 5863.853403664857, 4314.827406903752, 4359.738415695068, 4438.134563760101, 4491.8203271869415, 4506.864794056183, 4009.278329900646, 4033.6369726724733, 4060.39094135882, 4061.1065565575527, 4068.2880495913096, 5665.943340566624, 5677.347105082886, 5681.687507187145, 5686.402927692533, 5688.232088667977, 5698.572607054488, 5719.542436605062, 5756.366799652035, 5925.170476133345, 5933.910991335133, 5525.778968841201, 5581.2186293918885, 5584.944150558443, 5612.635178713371, 5617.691026993554, 5104.770285337168, 5109.795608175706, 5113.744312784346, 5114.71869047206, 5139.100657384943, 5800.60122729865, 5903.586748927534, 6036.034234522544, 6042.063480160582, 6051.7579296827635, 5836.328822185334, 5881.38245484218, 5900.5574581907385, 5938.049912014057, 5939.346671866005, 6133.325334213144, 6134.539909506716, 6138.570300078952, 6138.785142519991, 6147.231596050494, 5570.498655121059, 5829.183914251923, 5829.854253643693, 5832.212651292021, 5832.883342333044, 5712.514436272797, 5847.327557330961, 5889.852753681193, 5890.941090589039, 5968.76124954997, 5740.741666625096, 5790.913136303043, 5797.449242321973, 5799.768009279582, 5803.680797556175, 5737.885242295214, 5803.303603567599, 5826.213461182856, 5827.3007239131575, 5838.649681019152, 5531.944680553304, 5606.6916319602815, 5614.185943038155, 5676.943230567642, 5701.4298570142655, 5777.16231146473, 5784.161296612052, 5786.334571524329, 5806.158107074366, 5811.767529298781, 5446.074167391632, 5482.17767334903, 5672.2342483765815, 5675.943240567542, 5678.4605462480995], "value": 5396.9472771453175, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.7716724726484, 592.2212290837548, 604.5194070713782, 623.7224435126369, 631.003015235927, 35320.0, 38180.61819381805, 38537.807310963406, 38606.4209036864, 48907.26639100417, 49437.0, 49701.25448118272, 49707.0, 49716.254256186185, 49747.0, 9332.62669493221, 9436.480993545387, 9439.81120377592, 9450.291228157881, 9498.715038548855, 8592.527410992392, 8722.912770872274, 9265.258779297674, 9370.906290937106, 11121.27711698741, 11053.28153670009, 11445.141614378914, 11455.369954652533, 11455.770884582345, 11464.598739044135, 11285.605003824869, 11345.829812552818, 11350.829737553944, 11363.147763917657, 11367.147463940155, 11082.05802506788, 11164.0, 11168.832467512993, 11171.0, 11171.162162837738, 46042.0, 46349.07301853978, 46391.0, 46443.53556464426, 46446.0, 3799.1262009737916, 3815.67566756826, 3827.3302172119766, 3842.562237984532, 3944.2505923874373, 34854.65145348539, 34999.12502187466, 35224.29551408957, 35520.46719299187, 36653.26693466116, 4554.977225113887, 4653.930191047137, 4766.571008609207, 4766.833160839354, 4827.275908613701, 393274.0, 394232.0, 394663.0, 395566.0, 397192.9999999971, 39668.0, 40872.38691419631, 41055.178896422505, 66871.66564167148, 67258.66370668115, 41452.79273603613, 41623.0, 42043.7897810509, 42075.36886946698, 42093.78953105215, 16974.40589579359, 17192.914035429993, 17194.0, 17203.741943870853, 17215.311387544607, 14413.42346306157, 14521.419143234363, 14541.927290363481, 14555.272236388138, 14559.85440145585, 9161.221296189899, 9311.627534898527, 9526.237900967906, 9695.418274903528, 9746.707598772047, 3294.341131773655, 3303.7357011439026, 3311.602607687045, 3373.2916087621875, 3384.966150338465, 1047.4291511126303, 1067.4662668665667, 1069.1072954083427, 1101.3391964821203, 1153.429052618959, 34724.87186256319, 35003.82498087493, 35297.470537941954, 35585.28829423397, 35693.0, 1167.2121318110412, 1180.138498895811, 1228.4963165102265, 1231.901447884167, 1254.1158483269273, 18434.723479147822, 18488.815111848708, 18538.165782539818, 18723.25106995705, 18776.906115469334, 262733.0, 265525.0, 265630.0, 265783.0, 266009.0, 66404.0, 66715.66642166759, 66827.33172668309, 66842.33157668362, 66953.33046669568, 1442.077070674769, 1469.5150600301874, 1478.7633978563379, 1482.4366740638488, 1485.6137404274966, 33975.66024339824, 35130.82434587811, 35234.8238258807, 35277.647223527434, 35609.0, 56842.715786420806, 57182.142267866846, 57258.14112788395, 57370.713146434, 57450.42549574451, 481876.0, 483538.0, 484078.0, 484925.0, 485518.151683168, 31798.523022154222, 32296.515552266264, 32519.0, 32587.0, 32942.505862411606, 40540.59459405486, 44331.0, 50576.0, 52807.735961319944, 57197.42802571921, 44542.77728611336, 45190.0, 45210.547894520634, 45498.31752523649, 46391.0, 43918.56081439145, 45338.773306133255, 45802.0, 46450.76774616105, 47003.52996469991, 44846.0, 45738.77130614326, 45763.31355029611, 46330.30504542367, 46599.0, 37690.0, 39981.0, 40287.79856100701, 40955.59044409518, 41183.382249265684, 30426.84786576053, 30551.213817936743, 30785.538216927212, 30912.845435772677, 31016.379672405976], "value": 20329.623939993733, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.4521283235551, 401.43999121225875, 425.69775459423806, 433.53828172995765, 438.0406908869575, 18993.95533245671, 19765.901170494144, 20504.384868453933, 20748.0, 20777.376678699646, 19892.0, 19907.800921990776, 20238.29165979191, 20326.69509957348, 20419.28532501363, 5839.970800145993, 6117.051856962178, 6120.510359171256, 6123.326434092269, 6128.877422451548, 5896.911546326808, 5937.1984782054515, 5996.940030599681, 6030.788922387717, 6114.480269177126, 5920.348761636216, 5931.021381472052, 5945.0, 5950.55370847186, 5981.88036239275, 5761.4814666679995, 5968.343482216953, 5983.341832398411, 6044.063170208625, 6046.909296360558, 5432.864178395532, 5433.8369848904595, 5434.755436005389, 5438.293021907127, 5438.592105592096, 21951.341459756233, 23697.644535331983, 23704.40738981522, 23898.16356427534, 24039.27882163538, 2495.5508008558454, 2542.9872850635816, 2545.312765553309, 2554.961675574868, 2604.9869750651314, 15213.695726085527, 15230.238488075553, 15471.922640386727, 15586.0, 15864.444744434004, 1941.5243265400068, 1974.775639103765, 2030.0661695620033, 2061.752589689232, 2063.2881655828714, 84671.57664211701, 86058.56970715169, 86746.0, 87353.56323218407, 90361.54819225927, 11101.666949991513, 11311.151663625262, 11380.0, 11461.770764584662, 11479.655610331703, 16351.83648163527, 16370.836291636931, 16376.754348684779, 16381.672366552602, 16392.83607163937, 8628.180322869317, 8767.693130740394, 8800.95599522011, 8844.248238899765, 8958.148975847284, 8585.78535536604, 8604.48373097616, 8662.523561204194, 8831.55842207887, 8843.823123537622, 6892.793216203522, 6943.652817359111, 6962.3733863951475, 7007.824804379829, 7091.617134658795, 2050.1286953044896, 2060.7527096748186, 2071.140476702182, 2081.344376521391, 2094.6439105351824, 1022.1516141602452, 1033.8242498775226, 1034.9379037257788, 1093.3931667924219, 1130.739929816147, 20550.897245513675, 20612.38162855087, 21043.68434473484, 21145.78854211469, 21224.151033958777, 1078.7464945737745, 1106.994465027686, 1128.836318733779, 1152.8040233160216, 1162.988370116288, 10991.175661825395, 11735.061195104372, 11992.160548761678, 12038.939805300917, 12119.454624541917, 522524.0, 533192.0, 533299.0, 534503.0, 535354.0, 83288.0, 84502.0, 85005.0, 85860.0, 86991.0, 1086.5436516663021, 1088.1784252889177, 1107.5680484611007, 1110.228391268061, 1112.67732357617, 17950.0, 18325.3586124485, 18582.96769046637, 18761.90619046896, 19082.80917190866, 40070.398944016444, 40676.0, 41314.1737165249, 41709.1658166841, 42629.0, 53814.0, 54181.45818541764, 54386.45613543972, 55088.44911550994, 56407.0, 6309.716062777278, 8833.823323533365, 9027.0, 9172.35793494449, 9344.065593440602, 7735.265149810759, 12014.699632509257, 12522.436490358137, 12876.678083047998, 13616.455341786217, 11965.222260553037, 12135.939320303341, 12179.330136842558, 12289.201201921847, 12465.750684986431, 11569.19015668895, 11897.940510297392, 11948.0, 12190.268583884996, 12210.633680989584, 11116.666500005012, 11548.191626586058, 11559.537618495502, 12538.122331436713, 12679.556215532411, 9055.818883622422, 9175.220106290773, 9303.581338839904, 9345.626174953197, 9620.230381569598, 11894.762104758029, 12110.75778484443, 12189.27256443399, 12262.080343974416, 12568.748625027265], "value": 9802.731141565431, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.394572442401, 1481.6369989352606, 1508.3966413434625, 1677.3961373905393, 1821.6812057889865, 7517.661705223264, 8430.114837942012, 8681.956590217056, 8686.47881127132, 8963.641454341834, 8889.95555022224, 8901.510416927067, 8917.598708058136, 8929.642814287437, 8994.280457563396, 2427.4052857050046, 2449.4121410861444, 2452.791512721412, 2456.3981824453035, 2459.8155138364605, 2391.5814732421845, 2409.855408675476, 2446.9755302446924, 2451.6199989001734, 2453.3498622864868, 2477.4797292568583, 2481.04479775286, 2486.490269494758, 2500.8874600643016, 2516.1822407717473, 2386.4126046965384, 2400.6759087523137, 2411.7105947286263, 2412.0713525292713, 2426.587480128365, 2297.1041293895228, 2363.1492662641435, 2407.2296865003204, 2419.4919066995953, 2421.673074134987, 7556.622168891588, 7767.766966990999, 8888.911110888874, 8992.8651070234, 8998.505082220476, 5058.735316170957, 5225.399079105876, 5233.031889100528, 5287.47125287472, 5302.204669299597, 3462.8787992420143, 3468.479728040789, 3512.4906888501278, 3517.7009954153928, 3547.3260080584864, 1154.2958795134962, 1166.4867458318313, 1187.9049676025897, 1202.7774861650535, 1203.0616119426772, 12162.331071791048, 12254.387280636021, 12260.75478490434, 12272.447739851728, 12279.631611051684, 4485.304777759453, 4732.692374995614, 4772.976135119302, 4818.927716084261, 4841.370621819141, 10431.37411755297, 10451.68644940653, 10483.37099774016, 10485.318454300446, 10493.790124197474, 5171.663841850268, 5219.373675159005, 5244.737763111829, 5248.685078895202, 5253.290805741252, 4736.384270044872, 4788.449328327218, 4792.712437253706, 4792.904141917212, 4793.113274044313, 4091.97954010234, 4118.608732170439, 4144.979275103666, 4197.748135111842, 4197.9370309445385, 1317.5981325695755, 1328.3291937571464, 1329.7938819482995, 1346.104840281202, 1357.138217232045, 943.5895385507326, 964.3876138652007, 1030.7114008077751, 1039.095986491742, 1069.9304545204536, 5828.413892301756, 5904.793332233351, 5931.317898441648, 6037.396260373927, 6061.212042434543, 504.3393154967005, 524.0880867290887, 531.5481840435599, 545.3728212555513, 545.6671430427436, 3999.5200575931076, 4028.637422631977, 4072.490938632671, 4106.281400754856, 4114.485689288839, 9457.668981585612, 9796.755081122887, 10215.489225538842, 10498.160147188206, 10652.786944261072, 9519.095685909828, 9882.357646752938, 10825.07986821129, 10863.293885897392, 10865.674029779118, 7401.407887368998, 7541.924580754232, 7761.72833950809, 7979.122296547375, 8052.59737013147, 5830.18377427169, 5881.206037184926, 5929.881402371842, 5996.409691848476, 5998.430149135826, 9600.917888855744, 9794.853077203705, 9996.700098997042, 10049.145822604867, 10067.999999999707, 9847.901520984698, 9902.257330700082, 10064.295499314978, 10118.93751156118, 10154.390736555535, 7729.6521656524465, 7814.296713295831, 7815.101263354787, 7821.452498325063, 7824.921750782647, 8299.626516806878, 8359.540225287668, 8381.413301068867, 8425.789355266168, 8500.489970601537, 7308.671109800178, 7512.849743005218, 7569.5079819811535, 7716.652750626123, 7717.57553334572, 7456.366208872089, 7530.849383012419, 7552.320291173821, 7610.581418022062, 7668.884966725615, 6940.236573976757, 6962.895556566757, 7001.159860816734, 7066.646667666699, 7076.929230707627, 5137.17805151174, 5180.818671346485, 5277.736113194248, 5279.049771041249, 5290.312259406252, 5055.115354812967, 5126.384833819965, 5213.947860521498, 5279.868003299948, 5280.947190528045], "value": 4593.180788304734, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.1344537815125, 349.2299479647377, 350.8754392190774, 351.59566498526675, 352.94705794130886, 5296.020236256295, 5439.891202175964, 5490.972545137273, 5507.972460137704, 5524.1989911462815, 5215.191645294976, 5355.250264962909, 5365.973170134153, 5376.569874410047, 5380.7309634518315, 4300.22595932732, 4304.741715497064, 4306.18182545317, 4311.611954924057, 4312.482502099745, 4229.450171477703, 4275.828966841323, 4311.525732169459, 4313.935290970638, 4315.935260971088, 4252.255855225323, 4259.382389553512, 4275.679324050693, 4279.1441711657635, 4286.5284818669925, 4258.638015768633, 4267.786610669454, 4269.7011209215525, 4280.486341638992, 4283.550227226127, 3726.441033844918, 3811.294910441577, 3817.0723784488932, 3817.2556351511466, 3840.7133610240603, 5229.980153869997, 5250.396204436501, 5262.394824595182, 5263.578913686896, 5275.815346462856, 4873.731944743037, 4892.559669629715, 4893.0, 4900.754962251909, 4904.362432883738, 5396.379416367129, 5582.0, 5676.858078548028, 5679.801206957736, 5679.943200567983, 4121.278776214181, 4161.479815023122, 4164.5845409662625, 4166.7708276044805, 4167.895802604929, 5844.5616578756335, 5860.736266868002, 5864.853378665525, 5882.823515294548, 5909.409059094142, 4189.790510474464, 4243.130158317537, 4271.462273581508, 4275.422817919603, 4294.957050429518, 4329.19909816685, 4335.523092459827, 4345.500267469281, 4366.868993930188, 4380.6933514653665, 4899.314096026559, 4899.681520701143, 4918.589890953407, 4922.803087876445, 4929.260610908321, 5487.941128275423, 5723.227364305849, 5725.4274572543245, 5729.541636669056, 5735.8852822944145, 1485.4281101775894, 1507.3970411835353, 1510.2901636230954, 1513.137511618367, 1519.620094976256, 939.7744541310036, 965.9661911833052, 983.6409710455632, 985.5762022330467, 990.2078337330107, 4794.089123066607, 4861.586765124934, 4880.292357608127, 4901.926471102936, 4911.410630724265, 5090.36039231951, 5110.565601923878, 5124.359455068116, 5143.5113664201845, 5158.252053452228, 4642.908916404642, 4900.240462728283, 4905.583025442877, 4922.458529561745, 4944.703317800943, 2713.91858244253, 2725.2286014091, 2727.618133461317, 2728.808983371145, 2731.0063653533034, 5235.895282094337, 5241.449647787003, 5249.737513124405, 5254.894902101936, 5257.5005374489365, 4818.204996175662, 5148.330717006766, 5158.613104017214, 5181.3730388728045, 5205.282256855228, 5194.818181363709, 5244.265802787613, 5247.239150323258, 5262.42113367537, 5266.8419947401635, 5047.192449208109, 5068.873278168076, 5111.744412779271, 5118.488151184852, 5121.897562048813, 4713.599344055657, 4968.900621987612, 4969.751512424292, 4975.477574854588, 5055.898882022266, 5170.819021334235, 5181.689098654093, 5208.374995000624, 5211.921821172609, 5223.973880130575, 3831.6743076837665, 4110.547839737566, 4212.157568486254, 4235.682323825664, 4312.417823593774, 5835.854103647443, 5837.854053648693, 5842.474177324061, 5846.41535846412, 5850.502707269759, 5563.859408821101, 5720.799772007959, 5721.6280941740315, 5723.6851973141875, 5723.742431590672, 5669.262995810518, 5674.233978413026, 5684.7157642118555, 5689.800856969986, 5694.943050569607, 5631.352394474688, 5631.465010823965, 5660.575456840671, 5664.348599911063, 5669.177969194526, 5522.597670013557, 5719.628224165416, 5749.453801888814, 5760.049591817303, 5784.595078344475, 5582.581306401955, 5598.524125449382, 5609.663420194802, 5618.157276408571, 5645.745941432563], "value": 4210.213576030494, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [806.4274365200707, 812.2841906344919, 827.6978902700516, 872.3326655108839, 893.200585475999, 15425.614359641, 16440.506784796446, 16520.0913949733, 16542.917285413572, 16555.999999999985, 15739.0, 15974.281157347918, 16009.439669611565, 16023.27895244714, 16026.5993350166, 706.8798304288258, 724.4856152131993, 840.7099550655045, 871.6905498548019, 873.4846440600077, 874.2874557235832, 875.544716747291, 875.6847534887468, 875.7941883657368, 877.5261358866204, 922.3589605224372, 922.6678395777515, 923.6305477808896, 924.6393906376519, 924.8150369926008, 752.725255281821, 771.3906014248737, 877.2368039805347, 902.3908861518514, 917.0966597901062, 666.7233098264214, 670.4133882852504, 670.4770279182246, 670.7451168555966, 671.4326394196945, 15268.083914965138, 15294.847051529454, 15297.770533442008, 15320.387184512607, 15341.232938353149, 13873.0, 13956.302184890716, 14022.719545609132, 14091.647708807363, 14326.0, 12094.274343539328, 12224.816627750592, 12357.567485138066, 12403.503859845596, 12680.55618053373, 4549.818007279705, 4576.26779715244, 4606.631469482433, 4697.459792123916, 4809.95190048102, 83246.0, 87082.0, 87646.5617671914, 87648.99999999936, 88645.11354886433, 11270.718232044264, 11437.199396042197, 11438.370889601152, 11493.367864767519, 11697.473613687405, 21369.145234190768, 21438.678419823715, 21537.0, 21813.563728725338, 22300.776992230196, 8187.222213889671, 8227.177282271725, 8354.791130221793, 8430.11483794205, 8487.405881588229, 4704.623630109583, 4795.568398844121, 4802.375691160127, 4860.8298709545, 4928.260760885824, 3421.0934102462866, 3491.4762785582366, 3542.1852973816167, 3549.94675079874, 3553.022918697379, 569.2343797592232, 571.2573654249542, 573.1402895656515, 573.730346737033, 574.6293640601789, 621.3134486392471, 637.7480895046524, 672.3982036077683, 715.1346870286907, 726.5967388099656, 9004.59479323432, 9152.54237288133, 9188.8621670675, 9415.340926135243, 9476.573554190078, 675.3853992866468, 690.1614538335946, 695.5339922252103, 729.4565548666171, 782.0419985517696, 4497.662675299301, 4575.58819706228, 4581.0, 4659.813607455664, 4669.719816811003, 77327.61336193395, 126780.0, 129430.35284823515, 138253.0, 138503.0, 79321.0, 82348.0, 112308.43845780719, 114086.0, 115324.0, 87953.0, 89827.55086224528, 91189.0, 91290.0870991295, 92248.0, 8896.332775042027, 8939.553022348986, 8951.820963580562, 8998.955005224932, 9004.50475223869, 59814.0, 60162.42284914631, 60163.699181505566, 60550.0, 63094.0, 76102.23897760952, 76817.61591192009, 86119.56940215259, 86404.5679771597, 89811.55094224487, 12533.811992820298, 12832.78492372091, 12970.80543791825, 12984.15602985803, 13294.867051329364, 13082.869171308164, 13141.0, 13444.0, 13474.663133421744, 13504.797428038784, 10885.23803333759, 11104.0, 11497.712557186138, 11513.884861151617, 11801.586944457245, 12617.52147336529, 12638.2417054977, 12711.682207944876, 12725.172863763826, 12925.224486530838, 12043.81934270969, 12752.553660621834, 12868.0, 12882.09825312219, 13030.804537931748, 9861.260405469762, 10011.449370284705, 10046.296759226783, 10211.795764084529, 10383.792324153626, 10185.33795303303, 10314.226433017404, 10482.648583964716, 10638.308509946828, 10677.733056673645], "value": 7634.781856961247, "unit": "score"}], "name": "dromaeo_css", "value": 7358.922930881379, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 114.0, 111.0, 111.0, 111.0, 110.0, 111.0, 111.0, 110.0, 111.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 116.0, 121.0, 162.0, 118.0, 117.0, 119.0, 119.0, 145.0, 116.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 160.0, 161.0, 160.0, 161.0, 161.0, 161.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 111.0, 75.0, 75.0, 76.0, 75.0, 97.0, 76.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 124.0, 82.0, 82.0, 82.0, 100.0, 83.0, 82.0, 113.0], "value": 91.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 50.0, 49.0, 50.0, 50.0, 50.0, 49.0, 49.0, 49.0], "value": 49.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 58.0, 59.0, 59.0, 58.0, 58.0, 63.0, 59.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 74.0, 75.0, 73.0, 74.0, 73.0, 82.0, 73.0, 73.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 120.0, 122.0, 120.0, 117.0, 132.0, 118.0, 125.0, 154.0, 149.0], "value": 128.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 154.0, 155.0, 160.0, 156.0, 152.0, 157.0, 149.0, 151.0], "value": 153.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 61.0, 61.0, 59.0, 62.0, 59.0, 60.0, 60.0], "value": 61.0, "unit": "score"}], "name": "kraken", "value": 1453.3, "alertThreshold": 2.0}]} 03:50:42 INFO - Return code: 0 03:50:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:50:42 INFO - # TBPL SUCCESS # 03:50:42 INFO - Running post-action listener: _resource_record_post_action 03:50:42 INFO - [mozharness: 2016-11-14 11:50:42.342418Z] Finished run-tests step (success) 03:50:42 INFO - Running post-run listener: _resource_record_post_run 03:50:42 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 8192; Write bytes: 369926144; Read time: 272; Write time: 354440 03:50:42 INFO - TinderboxPrint: CPU usage
13.0% 03:50:42 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 272 03:50:42 INFO - TinderboxPrint: I/O write bytes / time
369,926,144 / 354,440 03:50:42 INFO - TinderboxPrint: CPU idle
8,273.6 (87.0%) 03:50:42 INFO - TinderboxPrint: CPU user
1,190.6 (12.5%) 03:50:42 INFO - TinderboxPrint: Swap in / out
0 / 0 03:50:42 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 124121088; Read time: 0; Write time: 116752 03:50:42 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 245239808; Read time: 248; Write time: 237644 03:50:42 INFO - Running post-run listener: _upload_blobber_files 03:50:42 INFO - Blob upload gear active. 03:50:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:50:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:50:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:50:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:50:43 INFO - (blobuploader) - INFO - Open directory for files ... 03:50:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:50:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:50:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:44 INFO - (blobuploader) - INFO - Done attempting. 03:50:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:50:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:50:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:44 INFO - (blobuploader) - INFO - Done attempting. 03:50:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:50:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:50:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:47 INFO - (blobuploader) - INFO - Done attempting. 03:50:47 INFO - (blobuploader) - INFO - Iteration through files over. 03:50:47 INFO - Return code: 0 03:50:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:50:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:50:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90010bd283b02d3ca2990eb493377d07e4e0141e3730ba6739c7cad37d90790ebf588d5c45ec880c90ccc7af39735fccccf19b95a0979cb7bef86163930c1bd9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6e64ec065261dcc0dc0b144186a85c59ffa0d47800bc83cf4dfecc283d1a0079f724fdd3d2e7d3e2034b56f1ab628375ef2c4652b94937f65e0513b160c86fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95b8b20f372612d989aec2e66e7fd164933573435895db46a26b5e4c7c1512907da4c8a8bb4b9ec0f5d67d1285976b9d6b887127a2b01f50ac30876397e055d3"} 03:50:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:50:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:50:47 INFO - Contents: 03:50:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90010bd283b02d3ca2990eb493377d07e4e0141e3730ba6739c7cad37d90790ebf588d5c45ec880c90ccc7af39735fccccf19b95a0979cb7bef86163930c1bd9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6e64ec065261dcc0dc0b144186a85c59ffa0d47800bc83cf4dfecc283d1a0079f724fdd3d2e7d3e2034b56f1ab628375ef2c4652b94937f65e0513b160c86fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95b8b20f372612d989aec2e66e7fd164933573435895db46a26b5e4c7c1512907da4c8a8bb4b9ec0f5d67d1285976b9d6b887127a2b01f50ac30876397e055d3"} 03:50:47 INFO - Running post-run listener: copy_logs_to_upload_dir 03:50:47 INFO - Copying logs to upload dir... 03:50:47 INFO - mkdir: /builds/slave/test/build/upload/logs 03:50:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.330285 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-11-14 03:50:46.987166) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 03:50:46.990997) ========= 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=5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90010bd283b02d3ca2990eb493377d07e4e0141e3730ba6739c7cad37d90790ebf588d5c45ec880c90ccc7af39735fccccf19b95a0979cb7bef86163930c1bd9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6e64ec065261dcc0dc0b144186a85c59ffa0d47800bc83cf4dfecc283d1a0079f724fdd3d2e7d3e2034b56f1ab628375ef2c4652b94937f65e0513b160c86fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95b8b20f372612d989aec2e66e7fd164933573435895db46a26b5e4c7c1512907da4c8a8bb4b9ec0f5d67d1285976b9d6b887127a2b01f50ac30876397e055d3"} build_url:https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011436 build_url: 'https://queue.taskcluster.net/v1/task/ZkginPMHTZKQ0jyrD1M8nw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90010bd283b02d3ca2990eb493377d07e4e0141e3730ba6739c7cad37d90790ebf588d5c45ec880c90ccc7af39735fccccf19b95a0979cb7bef86163930c1bd9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6e64ec065261dcc0dc0b144186a85c59ffa0d47800bc83cf4dfecc283d1a0079f724fdd3d2e7d3e2034b56f1ab628375ef2c4652b94937f65e0513b160c86fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95b8b20f372612d989aec2e66e7fd164933573435895db46a26b5e4c7c1512907da4c8a8bb4b9ec0f5d67d1285976b9d6b887127a2b01f50ac30876397e055d3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 03:50:47.015821) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 03:50:47.016138) ========= 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=5cf47e208febbcd81da23b2e0000026f-1479122445.180221-662259078 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004420 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 03:50:47.101392) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 03:50:47.101914) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 03:50:47.102357) ========= ========= Total master_lag: 0.17 =========