builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-028 starttime: 1479103270.79 results: success (0) buildid: 20161113192920 builduid: b3e4fd752f1b4dfda1a0c838b915fa25 revision: e9b3898d3366f830ce6acee568a7f68f6fc3eb8c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-13 22:01:10.793442) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-13 22:01:10.794014) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-13 22:01:10.794296) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-13 22:01:10.829886) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 22:01:10.830161) ========= 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=836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-13 22:01: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2016-11-13 22:01:11 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131223 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 22:01:10.981917) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 22:01:10.982232) ========= 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=836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017980 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 22:01:11.032080) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 22:01:11.032392) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e9b3898d3366f830ce6acee568a7f68f6fc3eb8c --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 e9b3898d3366f830ce6acee568a7f68f6fc3eb8c --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=836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-13 22:01:11,978 truncating revision to first 12 chars 2016-11-13 22:01:11,978 Setting DEBUG logging. 2016-11-13 22:01:11,978 attempt 1/10 2016-11-13 22:01:11,978 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e9b3898d3366?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-13 22:01:12,306 unpacking tar archive at: mozilla-aurora-e9b3898d3366/testing/mozharness/ program finished with exit code 0 elapsedTime=0.663115 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 22:01:11.716692) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-13 22:01:11.716993) ========= script_repo_revision: e9b3898d3366f830ce6acee568a7f68f6fc3eb8c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-13 22:01:11.717437) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-13 22:01:11.717730) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-13 22:01:11.728049) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-11-13 22:01:11.728318) ========= /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=836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:01:12 INFO - MultiFileLogger online at 20161113 22:01:12 in /builds/slave/test 22:01: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 22:01:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:01:12 INFO - {'append_to_log': False, 22:01:12 INFO - 'base_work_dir': '/builds/slave/test', 22:01:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:01:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:01:12 INFO - 'branch': 'Mozilla-Aurora', 22:01:12 INFO - 'buildbot_json_path': 'buildprops.json', 22:01:12 INFO - 'config_files': ('talos/linux_config.py',), 22:01:12 INFO - 'default_actions': ('clobber', 22:01:12 INFO - 'read-buildbot-config', 22:01:12 INFO - 'download-and-extract', 22:01:12 INFO - 'populate-webroot', 22:01:12 INFO - 'create-virtualenv', 22:01:12 INFO - 'install', 22:01:12 INFO - 'run-tests'), 22:01:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:01:12 INFO - 'download_minidump_stackwalk': True, 22:01:12 INFO - 'download_symbols': 'ondemand', 22:01:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:01:12 INFO - 'tooltool.py': '/tools/tooltool.py', 22:01:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:01:12 INFO - '/tools/misc-python/virtualenv.py')}, 22:01:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:01:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:01:12 INFO - 'installer_path': 'installer.exe', 22:01:12 INFO - 'log_level': 'info', 22:01:12 INFO - 'log_name': 'talos', 22:01:12 INFO - 'log_to_console': True, 22:01:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:01:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:01:12 INFO - 'opt_config_files': (), 22:01:12 INFO - 'pip_index': False, 22:01:12 INFO - 'sps_profile': False, 22:01:12 INFO - 'sps_profile_interval': 0, 22:01:12 INFO - 'suite': 'dromaeojs', 22:01:12 INFO - 'system_bits': '32', 22:01:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:01:12 INFO - 'title': 'talos-linux64-ix-028', 22:01:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:01:12 INFO - 'use_talos_json': True, 22:01:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:01:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:01:12 INFO - 'work_dir': 'build'} 22:01:12 INFO - [mozharness: 2016-11-14 06:01:12.764117Z] Running clobber step. 22:01:12 INFO - Running pre-action listener: _resource_record_pre_action 22:01:12 INFO - Running main action method: clobber 22:01:12 INFO - rmtree: /builds/slave/test/build 22:01:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:01:13 INFO - Running post-action listener: _resource_record_post_action 22:01:13 INFO - [mozharness: 2016-11-14 06:01:13.731661Z] Finished clobber step (success) 22:01:13 INFO - [mozharness: 2016-11-14 06:01:13.731762Z] Running read-buildbot-config step. 22:01:13 INFO - Running pre-action listener: _resource_record_pre_action 22:01:13 INFO - Running main action method: read_buildbot_config 22:01:13 INFO - Using buildbot properties: 22:01:13 INFO - { 22:01:13 INFO - "project": "", 22:01:13 INFO - "product": "firefox", 22:01:13 INFO - "script_repo_revision": "production", 22:01:13 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 22:01:13 INFO - "repository": "", 22:01:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 22:01:13 INFO - "buildid": "20161113192920", 22:01:13 INFO - "pgo_build": "True", 22:01:13 INFO - "basedir": "/builds/slave/test", 22:01:13 INFO - "buildnumber": 892, 22:01:13 INFO - "slavename": "talos-linux64-ix-028", 22:01:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:01:13 INFO - "platform": "ubuntu64_hw", 22:01:13 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:01:13 INFO - "branch": "mozilla-aurora", 22:01:13 INFO - "repo_path": "releases/mozilla-aurora", 22:01:13 INFO - "revision": "e9b3898d3366f830ce6acee568a7f68f6fc3eb8c", 22:01:13 INFO - "stage_platform": "linux64-pgo", 22:01:13 INFO - "builduid": "b3e4fd752f1b4dfda1a0c838b915fa25", 22:01:13 INFO - "slavebuilddir": "test" 22:01:13 INFO - } 22:01:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 22:01:13 INFO - Running post-action listener: _resource_record_post_action 22:01:13 INFO - [mozharness: 2016-11-14 06:01:13.734236Z] Finished read-buildbot-config step (success) 22:01:13 INFO - [mozharness: 2016-11-14 06:01:13.734325Z] Running download-and-extract step. 22:01:13 INFO - Running pre-action listener: _resource_record_pre_action 22:01:13 INFO - Running main action method: download_and_extract 22:01:13 INFO - mkdir: /builds/slave/test/build/tests 22:01: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')]} 22:01:13 INFO - trying https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 22:01:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/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 22:01:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/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 22:01:14 INFO - Downloaded 1361 bytes. 22:01:14 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 22:01:14 INFO - Using the following test package requirements: 22:01:14 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 22:01:14 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:01:14 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:01:14 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:01:14 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:01:14 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:01:14 INFO - u'jsshell-linux-x86_64.zip'], 22:01:14 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:01:14 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:01:14 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 22:01:14 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:01:14 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:01:14 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:01:14 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:01:14 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:01:14 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:01:14 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:01:14 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:01:14 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:01:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 22:01:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:01:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 22:01:14 INFO - Expected file size: 21962894 22:01: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 22:01:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 22:01:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:01:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 22:01:19 INFO - Expected file size: 21962894 22:01:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 22:01:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:01:24 INFO - Fetch https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 22:01:25 INFO - Expected file size: 14414089 22:01:25 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')]} 22:01:25 INFO - trying https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 22:01:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/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 22:01:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/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 22:01:27 INFO - Downloaded 64056837 bytes. 22:01:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 22:01:27 INFO - mkdir: /builds/slave/test/properties 22:01:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:01:27 INFO - Writing to file /builds/slave/test/properties/build_url 22:01:27 INFO - Contents: 22:01:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 22:01:27 INFO - Running post-action listener: _resource_record_post_action 22:01:27 INFO - Running post-action listener: set_extra_try_arguments 22:01:27 INFO - [mozharness: 2016-11-14 06:01:27.701119Z] Finished download-and-extract step (success) 22:01:27 INFO - [mozharness: 2016-11-14 06:01:27.701348Z] Running populate-webroot step. 22:01:27 INFO - Running pre-action listener: _resource_record_pre_action 22:01:27 INFO - Running main action method: populate_webroot 22:01:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:01:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:01:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:01:27 INFO - '--tppagecycles', 22:01:27 INFO - '7'], 22:01:27 INFO - 'tests': ['tsvgm']}}, 22:01:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:01:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:01:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:01:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:01:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:01:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:01:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:27 INFO - 'talos_options': ['--disable-e10s'], 22:01:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:27 INFO - 'talos_options': ['--disable-e10s'], 22:01:27 INFO - 'tests': ['damp', 'tps']}, 22:01:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:27 INFO - 'tests': ['damp', 'tps']}, 22:01:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:01:27 INFO - 'tests': ['dromaeo_dom']}, 22:01:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:01:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:01:27 INFO - 'tests': ['basic_compositor_video']}, 22:01:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:01:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:01:27 INFO - 'tests': ['a11yr', 22:01:27 INFO - 'ts_paint', 22:01:27 INFO - 'tpaint', 22:01:27 INFO - 'sessionrestore', 22:01:27 INFO - 'sessionrestore_no_auto_restore', 22:01:27 INFO - 'tabpaint']}, 22:01:27 INFO - 'other-e10s': {'tests': ['a11yr', 22:01:27 INFO - 'ts_paint', 22:01:27 INFO - 'tpaint', 22:01:27 INFO - 'sessionrestore', 22:01:27 INFO - 'sessionrestore_no_auto_restore', 22:01:27 INFO - 'tabpaint']}, 22:01:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:01:27 INFO - 'ts_paint', 22:01:27 INFO - 'tpaint', 22:01:27 INFO - 'sessionrestore', 22:01:27 INFO - 'sessionrestore_no_auto_restore', 22:01:27 INFO - 'tabpaint']}, 22:01:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:01:27 INFO - 'ts_paint', 22:01:27 INFO - 'tpaint', 22:01:27 INFO - 'sessionrestore', 22:01:27 INFO - 'sessionrestore_no_auto_restore', 22:01:27 INFO - 'tabpaint']}, 22:01:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:01:27 INFO - 'tests': ['a11yr', 22:01:27 INFO - 'ts_paint', 22:01:27 INFO - 'tpaint', 22:01:27 INFO - 'sessionrestore', 22:01:27 INFO - 'sessionrestore_no_auto_restore', 22:01:27 INFO - 'tabpaint']}, 22:01:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:01:27 INFO - 'tests': ['a11yr', 22:01:27 INFO - 'ts_paint', 22:01:27 INFO - 'tpaint', 22:01:27 INFO - 'sessionrestore', 22:01:27 INFO - 'sessionrestore_no_auto_restore', 22:01:27 INFO - 'tabpaint']}, 22:01:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:01:27 INFO - 'tests': ['tsvgx', 22:01:27 INFO - 'tsvgr_opacity', 22:01:27 INFO - 'tart', 22:01:27 INFO - 'tscrollx', 22:01:27 INFO - 'cart']}, 22:01:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:01:27 INFO - 'tsvgr_opacity', 22:01:27 INFO - 'tart', 22:01:27 INFO - 'tscrollx', 22:01:27 INFO - 'cart']}, 22:01:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:27 INFO - 'talos_options': ['--disable-e10s'], 22:01:27 INFO - 'tests': ['tp5o']}, 22:01:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:27 INFO - 'tests': ['tp5o']}, 22:01:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:27 INFO - 'talos_options': ['--disable-e10s', 22:01:27 INFO - '--xperf_path', 22:01:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:27 INFO - 'tests': ['tp5n']}, 22:01:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:27 INFO - 'talos_options': ['--xperf_path', 22:01:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:27 INFO - 'tests': ['tp5n']}}, 22:01:27 INFO - 'talos.zip': {'path': '', 22:01:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:01:27 INFO - Running post-action listener: _resource_record_post_action 22:01:27 INFO - [mozharness: 2016-11-14 06:01:27.722557Z] Finished populate-webroot step (success) 22:01:27 INFO - [mozharness: 2016-11-14 06:01:27.722646Z] Running create-virtualenv step. 22:01:27 INFO - Running pre-action listener: _resource_record_pre_action 22:01:27 INFO - Running main action method: create_virtualenv 22:01:27 INFO - Creating virtualenv /builds/slave/test/build/venv 22:01: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 22:01:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:01:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:01:27 INFO - Using real prefix '/usr' 22:01:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:01:28 INFO - Installing distribute.............................................................................................................................................................................................done. 22:01:30 INFO - Installing pip.................done. 22:01:30 INFO - Return code: 0 22:01:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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 0x7f1ece34ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a6f60>, '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 0x19a7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a81d0>, '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': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', '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 22:01: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 22:01: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 22:01:30 INFO - Using env: {'DISPLAY': ':0', 22:01:30 INFO - 'HOME': '/home/cltbld', 22:01:30 INFO - 'LANG': 'en_US.UTF-8', 22:01:30 INFO - 'LANGUAGE': 'en_US:en', 22:01:30 INFO - 'LOGNAME': 'cltbld', 22:01:30 INFO - 'MAIL': '/var/mail/cltbld', 22:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:30 INFO - 'MOZ_NO_REMOTE': '1', 22:01:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:30 INFO - 'NO_EM_RESTART': '1', 22:01:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:30 INFO - 'PWD': '/builds/slave/test', 22:01:30 INFO - 'SHELL': '/bin/bash', 22:01:30 INFO - 'SHLVL': '1', 22:01:30 INFO - 'TERM': 'linux', 22:01:30 INFO - 'TMOUT': '86400', 22:01:30 INFO - 'USER': 'cltbld', 22:01:30 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', 22:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:30 INFO - '_': '/tools/buildbot/bin/python'} 22:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01: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 22:01:30 INFO - Cleaning up... 22:01:30 INFO - Return code: 0 22:01:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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 0x7f1ece34ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a6f60>, '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 0x19a7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a81d0>, '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': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', '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 22:01: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 22:01: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 22:01:30 INFO - Using env: {'DISPLAY': ':0', 22:01:30 INFO - 'HOME': '/home/cltbld', 22:01:30 INFO - 'LANG': 'en_US.UTF-8', 22:01:30 INFO - 'LANGUAGE': 'en_US:en', 22:01:30 INFO - 'LOGNAME': 'cltbld', 22:01:30 INFO - 'MAIL': '/var/mail/cltbld', 22:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:30 INFO - 'MOZ_NO_REMOTE': '1', 22:01:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:30 INFO - 'NO_EM_RESTART': '1', 22:01:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:30 INFO - 'PWD': '/builds/slave/test', 22:01:30 INFO - 'SHELL': '/bin/bash', 22:01:30 INFO - 'SHLVL': '1', 22:01:30 INFO - 'TERM': 'linux', 22:01:30 INFO - 'TMOUT': '86400', 22:01:30 INFO - 'USER': 'cltbld', 22:01:30 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', 22:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:30 INFO - '_': '/tools/buildbot/bin/python'} 22:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:30 INFO - Downloading/unpacking psutil>=3.1.1 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:01:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:32 INFO - Installing collected packages: psutil 22:01:32 INFO - Running setup.py install for psutil 22:01:32 INFO - building 'psutil._psutil_linux' extension 22:01:32 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 22:01:32 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 22:01:33 INFO - building 'psutil._psutil_posix' extension 22:01: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 22:01: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 22:01:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:33 INFO - Successfully installed psutil 22:01:33 INFO - Cleaning up... 22:01:33 INFO - Return code: 0 22:01:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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 0x7f1ece34ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a6f60>, '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 0x19a7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a81d0>, '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': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', '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 22:01: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 22:01: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 22:01:33 INFO - Using env: {'DISPLAY': ':0', 22:01:33 INFO - 'HOME': '/home/cltbld', 22:01:33 INFO - 'LANG': 'en_US.UTF-8', 22:01:33 INFO - 'LANGUAGE': 'en_US:en', 22:01:33 INFO - 'LOGNAME': 'cltbld', 22:01:33 INFO - 'MAIL': '/var/mail/cltbld', 22:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:33 INFO - 'MOZ_NO_REMOTE': '1', 22:01:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:33 INFO - 'NO_EM_RESTART': '1', 22:01:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:33 INFO - 'PWD': '/builds/slave/test', 22:01:33 INFO - 'SHELL': '/bin/bash', 22:01:33 INFO - 'SHLVL': '1', 22:01:33 INFO - 'TERM': 'linux', 22:01:33 INFO - 'TMOUT': '86400', 22:01:33 INFO - 'USER': 'cltbld', 22:01:33 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', 22:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:33 INFO - '_': '/tools/buildbot/bin/python'} 22:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:01:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:01:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:01:35 INFO - Installing collected packages: mozsystemmonitor 22:01:35 INFO - Running setup.py install for mozsystemmonitor 22:01:35 INFO - Successfully installed mozsystemmonitor 22:01:35 INFO - Cleaning up... 22:01:35 INFO - Return code: 0 22:01:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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 0x7f1ece34ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a6f60>, '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 0x19a7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a81d0>, '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': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', '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 22:01: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 22:01: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 22:01:35 INFO - Using env: {'DISPLAY': ':0', 22:01:35 INFO - 'HOME': '/home/cltbld', 22:01:35 INFO - 'LANG': 'en_US.UTF-8', 22:01:35 INFO - 'LANGUAGE': 'en_US:en', 22:01:35 INFO - 'LOGNAME': 'cltbld', 22:01:35 INFO - 'MAIL': '/var/mail/cltbld', 22:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:35 INFO - 'MOZ_NO_REMOTE': '1', 22:01:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:35 INFO - 'NO_EM_RESTART': '1', 22:01:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:35 INFO - 'PWD': '/builds/slave/test', 22:01:35 INFO - 'SHELL': '/bin/bash', 22:01:35 INFO - 'SHLVL': '1', 22:01:35 INFO - 'TERM': 'linux', 22:01:35 INFO - 'TMOUT': '86400', 22:01:35 INFO - 'USER': 'cltbld', 22:01:35 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', 22:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:35 INFO - '_': '/tools/buildbot/bin/python'} 22:01:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:35 INFO - Downloading/unpacking jsonschema==2.5.1 22:01:35 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 22:01:35 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 22:01:35 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 22:01:35 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 22:01:35 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 22:01:35 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 22:01:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:01:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:37 INFO - Downloading functools32-3.2.3-2.tar.gz 22:01:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:01:38 INFO - warning: no files found matching '*.txt' 22:01:38 INFO - no previously-included directories found matching 'build' 22:01:38 INFO - no previously-included directories found matching 'dist' 22:01:38 INFO - no previously-included directories found matching '.git*' 22:01:38 INFO - Installing collected packages: jsonschema, functools32 22:01:38 INFO - Running setup.py install for functools32 22:01:38 INFO - warning: no files found matching '*.txt' 22:01:38 INFO - no previously-included directories found matching 'build' 22:01:38 INFO - no previously-included directories found matching 'dist' 22:01:38 INFO - no previously-included directories found matching '.git*' 22:01:38 INFO - Successfully installed jsonschema functools32 22:01:38 INFO - Cleaning up... 22:01:38 INFO - Return code: 0 22:01:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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 0x7f1ece34ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a6f60>, '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 0x19a7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a81d0>, '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': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', '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 22:01: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 22:01: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 22:01:38 INFO - Using env: {'DISPLAY': ':0', 22:01:38 INFO - 'HOME': '/home/cltbld', 22:01:38 INFO - 'LANG': 'en_US.UTF-8', 22:01:38 INFO - 'LANGUAGE': 'en_US:en', 22:01:38 INFO - 'LOGNAME': 'cltbld', 22:01:38 INFO - 'MAIL': '/var/mail/cltbld', 22:01:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:38 INFO - 'MOZ_NO_REMOTE': '1', 22:01:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:38 INFO - 'NO_EM_RESTART': '1', 22:01:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:38 INFO - 'PWD': '/builds/slave/test', 22:01:38 INFO - 'SHELL': '/bin/bash', 22:01:38 INFO - 'SHLVL': '1', 22:01:38 INFO - 'TERM': 'linux', 22:01:38 INFO - 'TMOUT': '86400', 22:01:38 INFO - 'USER': 'cltbld', 22:01:38 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', 22:01:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:38 INFO - '_': '/tools/buildbot/bin/python'} 22:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:01:38 INFO - Cleaning up... 22:01:38 INFO - Return code: 0 22:01:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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 0x7f1ece34ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a6f60>, '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 0x19a7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a81d0>, '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': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', '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 22:01: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 22:01: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 22:01:38 INFO - Using env: {'DISPLAY': ':0', 22:01:38 INFO - 'HOME': '/home/cltbld', 22:01:38 INFO - 'LANG': 'en_US.UTF-8', 22:01:38 INFO - 'LANGUAGE': 'en_US:en', 22:01:38 INFO - 'LOGNAME': 'cltbld', 22:01:38 INFO - 'MAIL': '/var/mail/cltbld', 22:01:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:38 INFO - 'MOZ_NO_REMOTE': '1', 22:01:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:38 INFO - 'NO_EM_RESTART': '1', 22:01:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:38 INFO - 'PWD': '/builds/slave/test', 22:01:38 INFO - 'SHELL': '/bin/bash', 22:01:38 INFO - 'SHLVL': '1', 22:01:38 INFO - 'TERM': 'linux', 22:01:38 INFO - 'TMOUT': '86400', 22:01:38 INFO - 'USER': 'cltbld', 22:01:38 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', 22:01:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:38 INFO - '_': '/tools/buildbot/bin/python'} 22:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:38 INFO - Downloading/unpacking blobuploader==1.2.4 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:40 INFO - Downloading blobuploader-1.2.4.tar.gz 22:01:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:01:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:01:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:41 INFO - Downloading docopt-0.6.1.tar.gz 22:01:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:01:41 INFO - Installing collected packages: blobuploader, requests, docopt 22:01:41 INFO - Running setup.py install for blobuploader 22:01:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:01:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:01:41 INFO - Running setup.py install for requests 22:01:41 INFO - Running setup.py install for docopt 22:01:41 INFO - Successfully installed blobuploader requests docopt 22:01:41 INFO - Cleaning up... 22:01:41 INFO - Return code: 0 22:01:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:41 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')]} 22:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:41 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')]} 22:01:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:41 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 0x7f1ece34ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a6f60>, '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 0x19a7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a81d0>, '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': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', '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 22:01:41 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 22:01:41 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 22:01:41 INFO - Using env: {'DISPLAY': ':0', 22:01:41 INFO - 'HOME': '/home/cltbld', 22:01:41 INFO - 'LANG': 'en_US.UTF-8', 22:01:41 INFO - 'LANGUAGE': 'en_US:en', 22:01:41 INFO - 'LOGNAME': 'cltbld', 22:01:41 INFO - 'MAIL': '/var/mail/cltbld', 22:01:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:41 INFO - 'MOZ_NO_REMOTE': '1', 22:01:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:41 INFO - 'NO_EM_RESTART': '1', 22:01:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:41 INFO - 'PWD': '/builds/slave/test', 22:01:41 INFO - 'SHELL': '/bin/bash', 22:01:41 INFO - 'SHLVL': '1', 22:01:41 INFO - 'TERM': 'linux', 22:01:41 INFO - 'TMOUT': '86400', 22:01:41 INFO - 'USER': 'cltbld', 22:01:41 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', 22:01:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:41 INFO - '_': '/tools/buildbot/bin/python'} 22:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:42 INFO - Running setup.py (path:/tmp/pip-8rXFQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:42 INFO - Running setup.py (path:/tmp/pip-ZxI6C9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:42 INFO - Running setup.py (path:/tmp/pip-CltZjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:42 INFO - Running setup.py (path:/tmp/pip-3s5Z1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:42 INFO - Running setup.py (path:/tmp/pip-TkJ_Qm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:42 INFO - Running setup.py (path:/tmp/pip-a4_40T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:42 INFO - Running setup.py (path:/tmp/pip-cHzTUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:42 INFO - Running setup.py (path:/tmp/pip-tsKVU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:42 INFO - Running setup.py (path:/tmp/pip-KWr2oi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:42 INFO - Running setup.py (path:/tmp/pip-t4p4Yj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:42 INFO - Running setup.py (path:/tmp/pip-jL8xE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:42 INFO - Running setup.py (path:/tmp/pip-xEcy51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:43 INFO - Running setup.py (path:/tmp/pip-yblwim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:43 INFO - Running setup.py (path:/tmp/pip-xVfXrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:43 INFO - Running setup.py (path:/tmp/pip-fS04xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:43 INFO - Running setup.py (path:/tmp/pip-vX1FW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:43 INFO - Running setup.py (path:/tmp/pip-CV1TZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:01:43 INFO - Running setup.py install for manifestparser 22:01:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:01:43 INFO - Running setup.py install for mozcrash 22:01:43 INFO - Running setup.py install for mozdebug 22:01:43 INFO - Running setup.py install for mozdevice 22:01:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:01:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:01:43 INFO - Running setup.py install for mozfile 22:01:43 INFO - Running setup.py install for mozhttpd 22:01:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:01:43 INFO - Running setup.py install for mozinfo 22:01:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:01:44 INFO - Running setup.py install for mozInstall 22:01:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:01:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:01:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:01:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:01:44 INFO - Running setup.py install for mozleak 22:01:44 INFO - Running setup.py install for mozlog 22:01:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:01:44 INFO - Running setup.py install for moznetwork 22:01:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:01:44 INFO - Running setup.py install for mozprocess 22:01:44 INFO - Running setup.py install for mozprofile 22:01:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:01:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:01:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:01:44 INFO - Running setup.py install for mozrunner 22:01:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:01:44 INFO - Running setup.py install for mozscreenshot 22:01:44 INFO - Running setup.py install for moztest 22:01:45 INFO - Running setup.py install for mozversion 22:01:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:01:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:01:45 INFO - Cleaning up... 22:01:45 INFO - Return code: 0 22:01:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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 0x7f1ece34ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a6f60>, '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 0x19a7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a81d0>, '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': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', '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 22:01: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 22:01: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 22:01:45 INFO - Using env: {'DISPLAY': ':0', 22:01:45 INFO - 'HOME': '/home/cltbld', 22:01:45 INFO - 'LANG': 'en_US.UTF-8', 22:01:45 INFO - 'LANGUAGE': 'en_US:en', 22:01:45 INFO - 'LOGNAME': 'cltbld', 22:01:45 INFO - 'MAIL': '/var/mail/cltbld', 22:01:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:45 INFO - 'MOZ_NO_REMOTE': '1', 22:01:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:45 INFO - 'NO_EM_RESTART': '1', 22:01:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:45 INFO - 'PWD': '/builds/slave/test', 22:01:45 INFO - 'SHELL': '/bin/bash', 22:01:45 INFO - 'SHLVL': '1', 22:01:45 INFO - 'TERM': 'linux', 22:01:45 INFO - 'TMOUT': '86400', 22:01:45 INFO - 'USER': 'cltbld', 22:01:45 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', 22:01:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:45 INFO - '_': '/tools/buildbot/bin/python'} 22:01:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:45 INFO - Running setup.py (path:/tmp/pip-5Wtl_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01: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)) 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:45 INFO - Running setup.py (path:/tmp/pip-wZ73S_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01: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)) 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:45 INFO - Running setup.py (path:/tmp/pip-HIIsJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01: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)) 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:45 INFO - Running setup.py (path:/tmp/pip-nGhaqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01: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)) 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:45 INFO - Running setup.py (path:/tmp/pip-8Qt5Cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:45 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)) 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:45 INFO - Running setup.py (path:/tmp/pip-omNqXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:45 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)) 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:45 INFO - Running setup.py (path:/tmp/pip-15GNDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:45 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)) 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:45 INFO - Running setup.py (path:/tmp/pip-mgk0EF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:45 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)) 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:45 INFO - Running setup.py (path:/tmp/pip-HI8_Dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01: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)) 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:46 INFO - Running setup.py (path:/tmp/pip-yk3Eog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01: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)) 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:46 INFO - Running setup.py (path:/tmp/pip-sSRrTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01: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)) 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:46 INFO - Running setup.py (path:/tmp/pip-mMRZRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01: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)) 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:46 INFO - Running setup.py (path:/tmp/pip-VAbtoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01: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)) 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:46 INFO - Running setup.py (path:/tmp/pip-S3h0Ij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01: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)) 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:46 INFO - Running setup.py (path:/tmp/pip-KL6r8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01: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)) 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:46 INFO - Running setup.py (path:/tmp/pip-5vHBLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01: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)) 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:46 INFO - Running setup.py (path:/tmp/pip-lygpcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01: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)) 22:01: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)) 22:01: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)) 22:01: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)) 22:01: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)) 22:01: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)) 22:01:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:48 INFO - Downloading blessings-1.6.tar.gz 22:01:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:01:48 INFO - Installing collected packages: blessings 22:01:48 INFO - Running setup.py install for blessings 22:01:48 INFO - Successfully installed blessings 22:01:48 INFO - Cleaning up... 22:01:48 INFO - Return code: 0 22:01:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:01:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:49 INFO - Reading from file tmpfile_stdout 22:01:49 INFO - Current package versions: 22:01:49 INFO - argparse == 1.2.1 22:01:49 INFO - blessings == 1.6 22:01:49 INFO - blobuploader == 1.2.4 22:01:49 INFO - docopt == 0.6.1 22:01:49 INFO - functools32 == 3.2.3-2 22:01:49 INFO - jsonschema == 2.5.1 22:01:49 INFO - manifestparser == 1.1 22:01:49 INFO - mozInstall == 1.12 22:01:49 INFO - mozcrash == 0.17 22:01:49 INFO - mozdebug == 0.1 22:01:49 INFO - mozdevice == 0.48 22:01:49 INFO - mozfile == 1.2 22:01:49 INFO - mozhttpd == 0.7 22:01:49 INFO - mozinfo == 0.9 22:01:49 INFO - mozleak == 0.1 22:01:49 INFO - mozlog == 3.4 22:01:49 INFO - moznetwork == 0.27 22:01:49 INFO - mozprocess == 0.23 22:01:49 INFO - mozprofile == 0.28 22:01:49 INFO - mozrunner == 6.12 22:01:49 INFO - mozscreenshot == 0.1 22:01:49 INFO - mozsystemmonitor == 0.3 22:01:49 INFO - moztest == 0.7 22:01:49 INFO - mozversion == 1.4 22:01:49 INFO - psutil == 3.1.1 22:01:49 INFO - requests == 1.2.3 22:01:49 INFO - wsgiref == 0.1.2 22:01:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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 0x7f1ece34ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a6f60>, '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 0x19a7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a81d0>, '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': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', '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 22:01: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 22:01: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 22:01:49 INFO - Using env: {'DISPLAY': ':0', 22:01:49 INFO - 'HOME': '/home/cltbld', 22:01:49 INFO - 'LANG': 'en_US.UTF-8', 22:01:49 INFO - 'LANGUAGE': 'en_US:en', 22:01:49 INFO - 'LOGNAME': 'cltbld', 22:01:49 INFO - 'MAIL': '/var/mail/cltbld', 22:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:49 INFO - 'MOZ_NO_REMOTE': '1', 22:01:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:49 INFO - 'NO_EM_RESTART': '1', 22:01:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:49 INFO - 'PWD': '/builds/slave/test', 22:01:49 INFO - 'SHELL': '/bin/bash', 22:01:49 INFO - 'SHLVL': '1', 22:01:49 INFO - 'TERM': 'linux', 22:01:49 INFO - 'TMOUT': '86400', 22:01:49 INFO - 'USER': 'cltbld', 22:01:49 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', 22:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:49 INFO - '_': '/tools/buildbot/bin/python'} 22:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01: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)) 22:01: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)) 22:01: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)) 22:01: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)) 22:01: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)) 22:01: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)) 22:01: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)) 22:01: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)) 22:01: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)) 22:01: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)) 22:01: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)) 22:01:49 INFO - Cleaning up... 22:01:49 INFO - Return code: 0 22:01:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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 0x7f1ece34ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a6f60>, '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 0x19a7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a81d0>, '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': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', '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 22:01: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 22:01: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 22:01:49 INFO - Using env: {'DISPLAY': ':0', 22:01:49 INFO - 'HOME': '/home/cltbld', 22:01:49 INFO - 'LANG': 'en_US.UTF-8', 22:01:49 INFO - 'LANGUAGE': 'en_US:en', 22:01:49 INFO - 'LOGNAME': 'cltbld', 22:01:49 INFO - 'MAIL': '/var/mail/cltbld', 22:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:49 INFO - 'MOZ_NO_REMOTE': '1', 22:01:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:49 INFO - 'NO_EM_RESTART': '1', 22:01:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:49 INFO - 'PWD': '/builds/slave/test', 22:01:49 INFO - 'SHELL': '/bin/bash', 22:01:49 INFO - 'SHLVL': '1', 22:01:49 INFO - 'TERM': 'linux', 22:01:49 INFO - 'TMOUT': '86400', 22:01:49 INFO - 'USER': 'cltbld', 22:01:49 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', 22:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:49 INFO - '_': '/tools/buildbot/bin/python'} 22:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:01:49 INFO - Cleaning up... 22:01:49 INFO - Return code: 0 22:01:49 INFO - Running post-action listener: _resource_record_post_action 22:01:49 INFO - Running post-action listener: _start_resource_monitoring 22:01:49 INFO - Starting resource monitoring. 22:01:49 INFO - [mozharness: 2016-11-14 06:01:49.495240Z] Finished create-virtualenv step (success) 22:01:49 INFO - [mozharness: 2016-11-14 06:01:49.495741Z] Running install step. 22:01:49 INFO - Running pre-action listener: _resource_record_pre_action 22:01:49 INFO - Running main action method: install 22:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:49 INFO - Reading from file tmpfile_stdout 22:01:49 INFO - Detecting whether we're running mozinstall >=1.0... 22:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:01:49 INFO - Reading from file tmpfile_stdout 22:01:49 INFO - Output received: 22:01:49 INFO - Usage: mozinstall [options] installer 22:01:49 INFO - Options: 22:01:49 INFO - -h, --help show this help message and exit 22:01:49 INFO - -d DEST, --destination=DEST 22:01:49 INFO - Directory to install application into. [default: 22:01:49 INFO - "/builds/slave/test"] 22:01:49 INFO - --app=APP Application being installed. [default: firefox] 22:01:49 INFO - mkdir: /builds/slave/test/build/application 22:01:49 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'] 22:01:49 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 22:02:05 INFO - Reading from file tmpfile_stdout 22:02:05 INFO - Output received: 22:02:05 INFO - /builds/slave/test/build/application/firefox/firefox 22:02:05 INFO - Running post-action listener: _resource_record_post_action 22:02:05 INFO - [mozharness: 2016-11-14 06:02:05.784558Z] Finished install step (success) 22:02:05 INFO - [mozharness: 2016-11-14 06:02:05.784804Z] Running run-tests step. 22:02:05 INFO - Running pre-action listener: _resource_record_pre_action 22:02:05 INFO - Running main action method: run_tests 22:02:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:02:05 INFO - Python 2.7.3 22:02:05 INFO - Return code: 0 22:02:05 INFO - grabbing minidump binary from tooltool 22:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:05 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 0x19a6f60>, '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 0x19a7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a81d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:02:05 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 22:02:05 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 22:02:05 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 22:02:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:02:05 INFO - Return code: 0 22:02:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:02:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:02:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:02:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:02:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:02:05 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/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 22:02:05 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/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --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 22:02:05 INFO - Using env: {'DISPLAY': ':0', 22:02:05 INFO - 'HOME': '/home/cltbld', 22:02:05 INFO - 'LANG': 'en_US.UTF-8', 22:02:05 INFO - 'LANGUAGE': 'en_US:en', 22:02:05 INFO - 'LOGNAME': 'cltbld', 22:02:05 INFO - 'MAIL': '/var/mail/cltbld', 22:02:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:02:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:05 INFO - 'MOZ_NO_REMOTE': '1', 22:02:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:02:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:05 INFO - 'NO_EM_RESTART': '1', 22:02:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:05 INFO - 'PWD': '/builds/slave/test', 22:02:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:02:05 INFO - 'SHELL': '/bin/bash', 22:02:05 INFO - 'SHLVL': '1', 22:02:05 INFO - 'TERM': 'linux', 22:02:05 INFO - 'TMOUT': '86400', 22:02:05 INFO - 'USER': 'cltbld', 22:02:05 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490', 22:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:05 INFO - '_': '/tools/buildbot/bin/python'} 22:02:05 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/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 22:02:06 INFO - mozversion application_buildid: 20161113192920 22:02:06 INFO - mozversion application_changeset: e9b3898d3366f830ce6acee568a7f68f6fc3eb8c 22:02:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:02:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:02:06 INFO - mozversion application_name: Firefox 22:02:06 INFO - mozversion application_remotingname: firefox-dev 22:02:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:02:06 INFO - mozversion application_vendor: Mozilla 22:02:06 INFO - mozversion application_version: 51.0a2 22:02:06 INFO - mozversion platform_buildid: 20161113192920 22:02:06 INFO - mozversion platform_changeset: e9b3898d3366f830ce6acee568a7f68f6fc3eb8c 22:02:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:02:06 INFO - mozversion platform_version: 51.0a2 22:02:06 INFO - using testdate: 1479103326 22:02:06 INFO - actual date: 1479103326 22:02:06 INFO - starting webserver on 'localhost:46044' 22:02:06 INFO - SUITE-START | Running 2 tests 22:02:06 INFO - TEST-START | dromaeo_css 22:02:06 INFO - operating with platform_type : linux_ 22:02:06 INFO - Initialising browser for dromaeo_css test... 22:02:06 INFO - TEST-INFO | started process 10058 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu5qTAn/profile http://localhost:46044/getInfo.html) 22:02:10 INFO - PROCESS | 10058 | __metrics Screen width/height:1600/1200 22:02:10 INFO - PROCESS | 10058 | colorDepth:24 22:02:10 INFO - PROCESS | 10058 | Browser inner width/height: 1024/697 22:02:10 INFO - PROCESS | 10058 | __metrics 22:02:10 INFO - PROCESS | 10058 | 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" 22:02:10 INFO - PROCESS | 10058 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:02:11 INFO - PROCESS | 10058 | console.error: PushService: 22:02:11 INFO - PROCESS | 10058 | stateChangeProcessEnqueue: Error transitioning state 22:02:11 INFO - PROCESS | 10058 | UnknownError 22:02:11 INFO - TEST-INFO | 10058: exit 0 22:02:11 INFO - Browser initialized. 22:02:11 INFO - Running cycle 1/1 for dromaeo_css test... 22:02:11 INFO - TEST-INFO | started process 10150 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu5qTAn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:02:23 INFO - PROCESS | 10150 | RSS: Main: 144367616 22:02:23 INFO - PROCESS | 10150 | 22:03:14 INFO - PROCESS | 10150 | 1479103394940 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] 22:05:24 INFO - PROCESS | 10150 | Cycle 1(1): loaded http://localhost:46044/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46044/tests/dromaeo/cssquery-ext.html) 22:05:25 INFO - PROCESS | 10150 | RSS: Main: 240091136 22:05:25 INFO - PROCESS | 10150 | 22:08:27 INFO - PROCESS | 10150 | Cycle 1(1): loaded http://localhost:46044/tests/dromaeo/cssquery-ext.html (next: http://localhost:46044/tests/dromaeo/cssquery-jquery.html) 22:08:28 INFO - PROCESS | 10150 | RSS: Main: 228241408 22:08:28 INFO - PROCESS | 10150 | 22:11:30 INFO - PROCESS | 10150 | Cycle 1(1): loaded http://localhost:46044/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46044/tests/dromaeo/cssquery-mootools.html) 22:11:30 INFO - PROCESS | 10150 | RSS: Main: 199389184 22:11:30 INFO - PROCESS | 10150 | 22:14:32 INFO - PROCESS | 10150 | Cycle 1(1): loaded http://localhost:46044/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46044/tests/dromaeo/cssquery-prototype.html) 22:14:33 INFO - PROCESS | 10150 | RSS: Main: 198905856 22:14:33 INFO - PROCESS | 10150 | 22:17:35 INFO - PROCESS | 10150 | Cycle 1(1): loaded http://localhost:46044/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46044/tests/dromaeo/cssquery-yui.html) 22:17:36 INFO - PROCESS | 10150 | RSS: Main: 176029696 22:17:36 INFO - PROCESS | 10150 | 22:20:38 INFO - PROCESS | 10150 | Cycle 1(1): loaded http://localhost:46044/tests/dromaeo/cssquery-yui.html (next: http://localhost:46044/tests/dromaeo/cssquery-dojo.html) 22:20:39 INFO - PROCESS | 10150 | RSS: Main: 262733824 22:20:39 INFO - PROCESS | 10150 | 22:20:39 INFO - PROCESS | 10150 | __start_tp_report 22:20:39 INFO - PROCESS | 10150 | _x_x_mozilla_page_load 22:20:39 INFO - PROCESS | 10150 | _x_x_mozilla_page_load_details 22:20:39 INFO - PROCESS | 10150 | |i|pagename|runs| 22:20:39 INFO - PROCESS | 10150 | |0;dojo.html;3168.8732450701973;3211.42194405007;3226.9677303226977;3243.675632436755;3257.6905194006563;5615.915761263579;5699.173619825133;5703.914441283378;5731.226284451608;5764.30828300604;5658.688772117543;5722.341930677973;5725.7995970141055;5726.0552008918585;5728.112142617901;5159.922601160967;5165.586753059765;5171.560417364529;5195.636305458619;5233.162693968966;5100.311457953185;5249.291345668324;5263.131583288753;5263.289455923439;5294.4176140624695;5122.129238029525;5125.282460455539;5128.076946149691;5172.27588137661;5187.740612969355;5177.430482646924;5278.340207474066;5279.1289437243;5280.128778751501;5280.471952804727;5140.023395554833;5179.404368497596;5246.580273578104;5256.159014557691;5274.472552744734;5482.945170548323;5542.2795036644975;5566.471185237396;5585.944140558583;5672.40439753824;6017.699115044274;6106.694665266719;6140.846478838065;6142.6314421134875;6147.969260153715;6150.261968563756;6167.41409566095;6171.382861713838;6174.536909731788;6174.691265436711;5531.059719847635;5569.10894256917;5624.100143976985;5696.886062278814;5698.8290351289525;6262.655553944577;6282.780102696383;6285.43431091204;6288.591241569284;6303.054541818672;6032.095185722089;6033.849153771191;6034.366391528914;6036.275646922398;6039.4262545058145;5767.653940763567;5827;6004.7598096076545;6099.390060993954;6126.111713801563;6107.917136652965;6114.049973376061;6119.080741749068;6129.059628562994;6129.450483694044;6115.571909966348;6140.3552626974415;6151.72317245725;6158.137860699506;6163.907541386883;4492.730436173774;4524.25349817283;4531.682782205214;4685.508021657677;4715.528447155257;4077.327241005201;4098.241825262336;4107.096438783463;4139.130782535641;4146.771927543954;5739.081746920556;5743.396943320891;5745.310562732415;5749.223854779635;5815.563832712563;5947.197128387699;6006.039033754579;6051;6052.606580572248;6055.485283750931;5700.971495142498;5751.194832723422;5763.971180144073;5846.824595262149;5882.705864706748;5264.736763161826;5294.708791016531;5301.469853014668;5309.123994540934;5310.522053015247;6165.290991536031;6296.5277604178955;6338.049292606146;6344.6827658616885;6356.555041147168;6059.6364218147055;6116.143739876332;6122.877542449215;6125.264968203844;6194.690265486798;6388.329225431263;6394.936050639434;6396.232452105777;6400;6404;6063.060225665029;6071.180390647382;6074.878502430015;6080.48315893154;6084;5867.677277749594;5922.851928701817;5992.250968628921;6001.669908155093;6015.338312785502;5904.439078287556;5919.200907877554;5919.526437885045;5920.674362910081;5928.673922934107;5912.763489460545;5924.022536281465;5924.7926322578505;5926.733297001731;5931.970340148444;5655.293088363955;5788.855278618068;5836.358000620014;5870.412958704095;5873.442023007808;6035.728392222272;6039.607425517327;6043.244594425697;6055.939440605537;6085.269767627913;5707.514861236841;5735.598508104393;5778.191053252632;5779.566532510163;5784.623999440023 22:20:39 INFO - PROCESS | 10150 | |1;ext.html;593.8604427959428;619.6096459230685;635.4535099814162;655.9147310849589;658.749675123453;34370;38324;38386.99999999996;45600;47266.29100563494;46893.7655311723;47180.056398872;47290;47486.52513474864;47513.287300690514;9278.118578735026;9434.716958491255;9434.716958491255;9439.622415103388;9446.1026202511;8438.493690378566;8590.312774978018;8638.093000234947;8792.912070879305;9150.588223529958;10927;11181.608643697473;11227.101831853473;11568.826467602992;11573.247738896987;11169;11229.213955023151;11249.887501125007;11255.437228138642;11256.887431125666;11023.393713345762;11028.117750580015;11051.115910727123;11091.778164436746;11092.389918554476;44369.334459982805;44707.55292447066;44724.552754472366;44759.552404475864;44804;3748.194138260287;4282.400463935051;4460.821567137311;4538.863834084983;4597.885052873671;33606.32787344264;33662.83168584166;33987.49018764721;34599.30801383958;35565.28869422623;4647.9767601161775;4673.065386922629;4702.553257440538;4710.199266124767;4891.070696567641;254660;254901;255728.99999999814;256385;257598;38721.22557548833;38879;59922;60307.396926031055;60603.69698151569;41635.791821041305;42087.789561052;42094.78952605279;42099.158016839494;42108;16358.263878125514;16514.42199523011;16523.33906643721;16525.752113718303;16529.504114876574;13640.590782276548;13718.725625487434;13734.587962361145;13737.931310343385;13738.793918091236;8950.194482496476;9101.954490227507;9105.089491050841;9573.808523829624;9597.113530819886;3555.2711694102645;3651.1419816343137;3668.6147954464395;3684;3776.3202623528027;1043.1341986151476;1051.574112484445;1057.3942854865027;1058.5606973106076;1137.886211378872;34198.35163467065;34529;34743.4788478173;34775;34779.30441391158;1155.5839897636845;1169.181572898974;1173.0439691651277;1192.66605350502;1201.963941081769;17817.821821781876;18193.7270940936;18194.363197288032;18203.362882299058;18375.72436413455;606535;613528;614051;616120;617766;55088.17367739487;55833;61393.386066138766;61762.382376175665;61860.38139618636;1354.959351219465;1393.9721205575831;1394.1774353131498;1430.4135304525303;1434.0463591711602;33610.83194584012;35257.29485410329;35420.291594168484;35504.64495355013;35697.46453803143;53195;53673;53699;53793.462065378844;53795;408230;526908;542499;542530;866636;45725.54274457213;45741.771291143334;45822.54177458316;46069;46242.53757462382;51318.23022654738;52090.47909520856;52392;52397.21404178864;52562.737186313825;42213;42839.14321713611;42911.356329655704;43493.565064350216;44230;43234.783826081926;43763.12473750571;43780.34329485124;44079.77960110179;44946.550534494236;43145.784271078446;43676.126477470905;44478;44635.553644463136;44656.33015504705;35712.642873570934;38502.00624164044;38832.6116738829;39104.41343379908;39575.40636890391;29041.530379621538;29114.56328155037;29199;29706;29724.851375742983 22:20:39 INFO - PROCESS | 10150 | |2;jquery.html;399.1358708396322;412.75853625629003;428.939948407223;434.09274616052454;436.75323442255143;13085.476580936775;14405.423783048678;19681;19681.507962300948;19717;21681.3495595132;22167.889160554216;22833.200837970675;22846.428839279026;22997.655035174485;6457.096006559085;6567.408933196011;6613.074169616233;6645.867082658344;6666.966665166691;6374.872502549946;6558.1802274715665;6655.500837437187;6655.600663960154;6657.733690652368;6539.64031978241;6583.835404114888;6585.308542603029;6585.374389432996;6585.835354116138;6388.968055159718;6571.671416429183;6582.440492558138;6584.670766461658;6697.464202863759;6455.096286519891;6506.60960342381;6521.021846722982;6523.739050437976;6529.053287273318;25470.10854620098;26162.6075608866;26307.605385919225;27688.584671229946;27954.860225698943;2520.5841036229;2613.451175253199;2620.842749435021;2648.2187754612437;3274.4597141471627;15417.614559635987;16826.579335516588;17047.48857534276;17085.231164597622;17710.29158833645;2138.454694053022;2190.8575942563843;2207.0399376271375;2267.841251112406;2310.8266879984067;95590;99540;99742;100965;103795;18702;18807;19384.127714252892;19461;19480.220791168478;35235.471467928;35253;35328.823355883054;35409.46885796715;35519.82240088835;9995.500224988722;10007.199424046059;10011.349262297927;10020;10128.442935638463;8114.391420643476;8116.797080072927;8146.348292136615;8286.46138001028;8381.07808141104;9227.308313595757;9266.212371948324;9278.257739380833;9534.237261019101;9616.9038309616;3396.626371099177;3546.0142320300715;3551.1477245460906;3574.5148480232087;3575.8927232183078;951.2627713521938;1007.0785231513083;1059.0944742245424;1060.8992145746142;1076.359566058194;19598;20094.39716808498;20117.597648046958;20350.18599256013;20560.177592896118;1021.7496713305288;1029.624187171677;1042.8957104289661;1082.3830416662427;1089.455272363818;10617.84073238902;11330;11381.715457113638;11407.372594507216;11425.828612570818;500295;502833;509856;510941;513405;100531.49734251283;101730.49134754279;102932;103022.79707358425;104914.4754276239;1123.859517560305;1170.2861254634665;1175.6893031950185;1189.2270024483983;1201.1351826684772;17722.91138544299;18062.36781712634;18189.909050454662;18387;18460.723089153405;45474.772626136655;46981;47113;47230.05539889252;48130.75934620304;63183.6840815793;64192;64875.67562162159;66915;67210.32789672041;8371.41400101987;8395.538245396561;8843.204111629984;9284.860727088964;9566.712998610054;10060.49697515136;12697.492100315883;12800.551980680631;13110.213387196798;13649.658758530719;11561.42192890369;11686.94156529246;12061.51753929833;12373.195742276723;12500.749985000431;11251.437428128724;11641.476133574179;11875.703107422383;11918.761624767629;12528.060395470195;10871.728206794576;10925.235233533576;11087.501062452047;11489.942550287195;12555.623331300076;8794.868076978722;8863.64545418176;8963.372563920462;9207.76980575491;9429.57566909477;10101.89898101039;10190;11149.665510034712;11173.273737207055;11249.606263780728 22:20:39 INFO - PROCESS | 10150 | |3;mootools.html;1424.935877885495;1555.1291276884942;1585.9841401585984;1740.6170642458655;1876.6622008038548;8377.329813614911;9017.909820901788;9329.486878221694;9413.717588472357;9429.717108486739;8913.153250441213;9123.452592844435;9133.954330228356;9322.813543729122;9325.300602454809;2496.900123995038;2543.618457231421;2543.872806359684;2545.5163518931345;2547.1849008317254;2459.963100553493;2530.9493810123786;2531.392465808211;2531.8101142414303;2532.126416386353;2488.6267059940974;2501.6497690323367;2528.6207068939716;2532.708738495069;2536.1250368622796;2461.52000359929;2468.8024958003316;2473.468204336067;2508.749125087495;2513.572692642237;2428.9392765180837;2436.8781560921884;2441.853488790678;2442.120836498859;2482.453860150764;9326.48704321262;9634.855477167848;9672.70981870545;9714.708558743248;9736.902630973742;5624.409437009137;5875.059990401515;5890.087036509348;5890.941090589082;5897.616654917417;3659.1949771050326;3676.1544844685604;3735.346314394997;3744.6255374462335;3749.325121478133;1240.5347994502063;1245.906557008219;1249.4065318973505;1281.5642681488246;1284.3514025417105;12622.873771262353;12850.036247281398;12853.485860565566;12869.871301286961;12882.1626594272;4872.1473742094995;4889.755512224375;4983.704236898432;5059.949400505948;5061.316722242524;11228.887711122947;11242.66272011841;11255.155863310285;11268.436578171222;11275.37985410794;5707.0583353747;5716.056850619601;5717.285339332583;5721.771129154788;5723.341815691166;4525.570070843265;4579.748113853703;4643.257078867365;4836.056968891033;5214.765335559909;4376.40918476008;4437.844675436409;4530.796114174804;4546.704464209815;4552.544745525421;1375.7454870848926;1402.4390243902317;1411.978820317696;1421.779624158257;1424.3875133692404;997.436448406648;1003.8193125237527;1016.761061150629;1020.2959957629212;1022.2997246885907;6077.726502307407;6089.908651370233;6147.907781383283;6213.409726076015;6365.681715914186;498.3197934818946;501.41835470853636;508.8041104174919;511.2076281763252;514.6320380927655;4147.543770185277;4263.5096963849555;4288.4210631564965;4302.22559939214;4370.825166993284;11857.64427067172;12310.75378492425;12351.814722779165;12368.257904525757;12372.072094592762;11951.22317049389;12255.264684118982;12279.140460167702;12306.569270075504;12336.136470447162;9021.684241051531;9179.495127768037;9209.861852072225;9216.861747073799;9225.58484868169;6157.199564056644;6158.938410615837;6205;6226;6307.936920630735;10914.672559823219;10986.285891417032;10990;11329.206955513035;11345.943270283597;9672.419654820733;9785.168260697921;11237.269577477438;11430.657080287605;11519.481623326803;8391.832163356821;8656.091110433314;8756;8756.343274254328;8759.080296569024;9362.625494980124;9378.531073446436;9438.48088355147;9446.338756286994;9448.95275523618;8257.21556452136;8473.195046470575;8637.093105223861;8792.120787921413;8837;8003.11965683786;8093.676252949816;8128.715494957648;8266.875996859932;8379.874301885355;7340.192578816219;7379.631018449163;7393.371563417169;7442.144153422426;7487.3261406473675;4948.529889660476;4950.950490495049;5034;5090.41460232078;5129.230615407719;5254.211868219798;5274.261603375608;5299.735013249245;5358.571314294925;5365.839024829262 22:20:39 INFO - PROCESS | 10150 | |4;prototype.html;350.83510749947516;358.3996805351037;365.0745360511105;368.43997124370975;369.2964901861954;5315.39545622645;5434.266374039514;5460.972695136524;5525.088360420531;5536.4186760390185;5182.807954170543;5334.786608535664;5386.111291636876;5390.245365648812;5404.729763511828;4327.329263964075;4335.783210839445;4341.674374421915;4344.717593356421;4372.803223854934;4206.737978606421;4324.718893271943;4355;4364.9781751091205;4368.322909948963;4293.914121717564;4315.41741864849;4321.524632290446;4321.783910804463;4346.587074227943;4298.247806633826;4298.785060746965;4304.117655880537;4327.480702315711;4339.696221264513;3653.30587188436;3797.829097690583;3800.619938006205;3813.694904407641;3831.8467261309515;5265.736713164365;5278.445763194848;5280.313559237313;5290.989424018887;5295.894082118374;4899.730514821684;4902.480231128362;4939.259111133323;4946.653734238605;4970.552650261494;5494.917576236359;5665.3201615806365;5668.348139963916;5668.43315668434;5673.12066629669;4116.670666346685;4128.298189307825;4142.689298302609;4146.688998325108;4149.522804877417;5756.532084318514;5875.970620146914;5928.911066334009;5947.494462970652;5974.790882319098;4220.134872351161;4229.302165142779;4235.5552666969525;4288.4210631564965;4299.032717638506;4339.7830108494445;4344.869653910388;4345.000649850489;4347.413099231627;4350.434443522385;4887.65786394956;4913.385826771653;4915.21356582945;4923.236898280772;4946.431160416598;5368.91946620801;5591.692456914828;5626.718664066781;5678.971605141948;5701.657900525982;1413.187417235094;1433.8637829406189;1485.851414858527;1494.5815171751926;1494.796688665613;1008.9646862359929;1016.1362841584594;1033.9172866170688;1036.5957276662107;1043.3531210649446;5443;5453.400125986218;5465.234867118606;5465.863353416196;5473.17902314656;5708.429157084258;5723.112917497711;5756.33802112754;5759.740811663485;5810.12848072784;5257.63196576236;5537.944620553823;5562.22128901946;5567.66594004361;5597.384287728347;3082.2602575382193;3087.5368694695526;3095.6998060814512;3096.690330966885;3117.314190878003;5772.624779389326;6052.368578852741;6070.757169713251;6100.54245931548;6102.603330783485;5975.731092100866;5978.983572792636;5997;5999.670018148957;6013.54898382623;6037.78867739627;6049.425304596057;6056.818295451144;6060.848478787977;6075.635461872125;5616.66300022;5820.883582328245;5855.180274761452;5863.648181109147;5880.617759845596;5753.395893431297;5761.971190144022;5773.653580785166;5843.795467158629;5854.975379308689;5835.708214589169;6070.2412198475195;6082.969585152223;6093.130861902635;6104.542159338156;4198.349255865346;4216.566367435087;4239.4488716467895;4250.447441832542;4256.212600668886;5669.914951275817;5849.736761845642;5856.159762515262;5913.2608423947;5922.763089476372;5387.676739395649;5702.40124786908;5717.742701578356;5760.94239057604;5775.27809023872;5679;5728.825590753802;5736.627119237236;5739.512141468022;5743.8851222976145;5550.7510810067415;5612.607117501736;5615.2700148982;5618.719064046863;5665.348484924287;5708.115242137475;5727.3940357138235;5736.856578585569;5754.913676294943;5773.498890288472;5482.30048684909;5593.448160418083;5625.315418400954;5634.267545219095;5640 22:20:39 INFO - PROCESS | 10150 | |5;yui.html;786.2137862137862;795.4670370851529;850.2262940723939;879.7976465412959;928.5403725156049;16366.83633163668;16400.343986240536;16504.339826406944;16610.335586576522;16778.41275555356;16127.758083628754;16131.91934040331;16146.434874779385;16262.51212463625;16297.266623001964;718.9964050179742;737.0124033794714;833.5248908122358;853.4751128056232;875.3653601139187;865.2040123086744;868.0191383736401;869.074435725953;869.2220462685892;869.8042940338437;919.7332773495712;921.0835218957147;921.5760750054983;922.524899676667;922.7508572685371;756.7691853984533;774.9845003099935;888.1074520107284;903.0472851142042;914.7713071732067;664.7141729056503;665.7137430904709;666.0176240045942;666.2671061831994;666.4335314982275;15006.774898376532;15057.397704091823;15065.096094234272;15073.773893391608;15076.924615376962;15190.177178738511;15663.530094097081;15693.215339232993;15824.76262856058;16176.191190440548;14122.505712300122;14224.786628200585;14298.714025719531;14640;14679.412823487048;4695.3426520287185;4725.220338644154;4784.306275590055;4820.469748327716;4908.9263661045115;87375.56312218399;87454.5627271866;87529.56235218783;87601;88089.55955220183;12450;12519.248845069325;12541.31022793755;12549.749005019848;12550.1842380245;23141.53716925652;23204.7679523206;23351.182708605458;23356.7664323358;23956.88021559881;8537.74386768398;8823.647054117891;8841.823163536694;8849.292056635453;9141.177294043435;5073.695578265316;5140.177571588528;5161.845144645667;5214.45248248936;5294.682319060868;3783.8675646352244;3923.7449565778134;3948.2498325318097;3948.7235893487755;3955.564887862333;681.6012632609879;686.1560280854577;695.006141218063;715.8675645005691;724.3625609463744;719.2807192807193;747.5888261456178;765.5674543882689;777.7433446962489;797.6809276289415;9490.667826626035;9788.21694264457;10072.949635251776;10076.294659373774;10220.131288840383;661.6162625677192;689.3416786968434;755.3730403764972;764.7629234937076;771.0246538129271;4646.163690535736;4945.530174633404;4956.207006878882;4977.527134922177;4986.800527978913;74359.25640743523;75421;75645;106593.4670326659;115787.42106289415;112534.43732781448;115622.42188789003;117574;117635;117963.41018294853;71140.64429677922;89561;98100.50949745349;98465.50767246119;101312.49343753382;9442.291828112782;9480.146786789222;9488.48262158898;9544.665936692183;9739.756506087404;44908.10183796371;45499;49726.751366242934;58365.416345836;58658.413415865296;51854.22218666648;65995.6700216515;80019.79970300567;84908.57545712232;85658.57170714106;14375.28123593837;14416.495422660157;14597.635059123606;14653.633659158606;14797.704045919236;15825;16077;16272.267747951133;16288.267027983533;16679;14536.636584085483;14546.345414456164;14941.252937353307;15111.773323400377;15215.239238038275;13537.72924541484;14143.292835358397;14339.498117565836;15000.774988374966;15556.144412056992;13746.725065498435;14439.061461005002;14599.416023359372;14855.702885942437;15041.398344066114;11919.82120268214;12077.335746533674;12735.745285094432;12762.744745105232;13139;10808.56765729362;11282.323060616389;12664.6833829155;12743.74512509726;12931 22:20:39 INFO - PROCESS | 10150 | __end_tp_report 22:20:39 INFO - PROCESS | 10150 | __start_cc_report 22:20:39 INFO - PROCESS | 10150 | _x_x_mozilla_cycle_collect,820 22:20:39 INFO - PROCESS | 10150 | __end_cc_report 22:20:39 INFO - PROCESS | 10150 | __startTimestamp1479104439191__endTimestamp 22:20:39 INFO - PROCESS | 10150 | 22:20:39 INFO - PROCESS | 10150 | ------- Summary: start ------- 22:20:39 INFO - PROCESS | 10150 | Number of tests: 6 22:20:39 INFO - PROCESS | 10150 | 22:20:39 INFO - PROCESS | 10150 | [#0] dojo.html Cycles:165 Average:5654.14 Median:5836.36 stddev:657.34 (11.3%) stddev-sans-first:629.78 22:20:39 INFO - PROCESS | 10150 | Values: 3168.9 3211.4 3227.0 3243.7 3257.7 5615.9 5699.2 5703.9 5731.2 5764.3 5658.7 5722.3 5725.8 5726.1 5728.1 5159.9 5165.6 5171.6 5195.6 5233.2 5100.3 5249.3 5263.1 5263.3 5294.4 5122.1 5125.3 5128.1 5172.3 5187.7 5177.4 5278.3 5279.1 5280.1 5280.5 5140.0 5179.4 5246.6 5256.2 5274.5 5482.9 5542.3 5566.5 5585.9 5672.4 6017.7 6106.7 6140.8 6142.6 6148.0 6150.3 6167.4 6171.4 6174.5 6174.7 5531.1 5569.1 5624.1 5696.9 5698.8 6262.7 6282.8 6285.4 6288.6 6303.1 6032.1 6033.8 6034.4 6036.3 6039.4 5767.7 5827.0 6004.8 6099.4 6126.1 6107.9 6114.0 6119.1 6129.1 6129.5 6115.6 6140.4 6151.7 6158.1 6163.9 4492.7 4524.3 4531.7 4685.5 4715.5 4077.3 4098.2 4107.1 4139.1 4146.8 5739.1 5743.4 5745.3 5749.2 5815.6 5947.2 6006.0 6051.0 6052.6 6055.5 5701.0 5751.2 5764.0 5846.8 5882.7 5264.7 5294.7 5301.5 5309.1 5310.5 6165.3 6296.5 6338.0 6344.7 6356.6 6059.6 6116.1 6122.9 6125.3 6194.7 6388.3 6394.9 6396.2 6400.0 6404.0 6063.1 6071.2 6074.9 6080.5 6084.0 5867.7 5922.9 5992.3 6001.7 6015.3 5904.4 5919.2 5919.5 5920.7 5928.7 5912.8 5924.0 5924.8 5926.7 5932.0 5655.3 5788.9 5836.4 5870.4 5873.4 6035.7 6039.6 6043.2 6055.9 6085.3 5707.5 5735.6 5778.2 5779.6 5784.6 22:20:39 INFO - PROCESS | 10150 | 22:20:39 INFO - PROCESS | 10150 | [#1] ext.html Cycles:180 Average:64260.57 Median:42103.58 stddev:138321.90 (328.5%) stddev-sans-first:138627.33 22:20:39 INFO - PROCESS | 10150 | Values: 593.9 619.6 635.5 655.9 658.7 34370.0 38324.0 38387.0 45600.0 47266.3 46893.8 47180.1 47290.0 47486.5 47513.3 9278.1 9434.7 9434.7 9439.6 9446.1 8438.5 8590.3 8638.1 8792.9 9150.6 10927.0 11181.6 11227.1 11568.8 11573.2 11169.0 11229.2 11249.9 11255.4 11256.9 11023.4 11028.1 11051.1 11091.8 11092.4 44369.3 44707.6 44724.6 44759.6 44804.0 3748.2 4282.4 4460.8 4538.9 4597.9 33606.3 33662.8 33987.5 34599.3 35565.3 4648.0 4673.1 4702.6 4710.2 4891.1 254660.0 254901.0 255729.0 256385.0 257598.0 38721.2 38879.0 59922.0 60307.4 60603.7 41635.8 42087.8 42094.8 42099.2 42108.0 16358.3 16514.4 16523.3 16525.8 16529.5 13640.6 13718.7 13734.6 13737.9 13738.8 8950.2 9102.0 9105.1 9573.8 9597.1 3555.3 3651.1 3668.6 3684.0 3776.3 1043.1 1051.6 1057.4 1058.6 1137.9 34198.4 34529.0 34743.5 34775.0 34779.3 1155.6 1169.2 1173.0 1192.7 1202.0 17817.8 18193.7 18194.4 18203.4 18375.7 606535.0 613528.0 614051.0 616120.0 617766.0 55088.2 55833.0 61393.4 61762.4 61860.4 1355.0 1394.0 1394.2 1430.4 1434.0 33610.8 35257.3 35420.3 35504.6 35697.5 53195.0 53673.0 53699.0 53793.5 53795.0 408230.0 526908.0 542499.0 542530.0 866636.0 45725.5 45741.8 45822.5 46069.0 46242.5 51318.2 52090.5 52392.0 52397.2 52562.7 42213.0 42839.1 42911.4 43493.6 44230.0 43234.8 43763.1 43780.3 44079.8 44946.6 43145.8 43676.1 44478.0 44635.6 44656.3 35712.6 38502.0 38832.6 39104.4 39575.4 29041.5 29114.6 29199.0 29706.0 29724.9 22:20:39 INFO - PROCESS | 10150 | 22:20:39 INFO - PROCESS | 10150 | [#2] jquery.html Cycles:180 Average:32187.45 Median:13995.35 stddev:84047.55 (600.5%) stddev-sans-first:84249.43 22:20:39 INFO - PROCESS | 10150 | Values: 399.1 412.8 428.9 434.1 436.8 13085.5 14405.4 19681.0 19681.5 19717.0 21681.3 22167.9 22833.2 22846.4 22997.7 6457.1 6567.4 6613.1 6645.9 6667.0 6374.9 6558.2 6655.5 6655.6 6657.7 6539.6 6583.8 6585.3 6585.4 6585.8 6389.0 6571.7 6582.4 6584.7 6697.5 6455.1 6506.6 6521.0 6523.7 6529.1 25470.1 26162.6 26307.6 27688.6 27954.9 2520.6 2613.5 2620.8 2648.2 3274.5 15417.6 16826.6 17047.5 17085.2 17710.3 2138.5 2190.9 2207.0 2267.8 2310.8 95590.0 99540.0 99742.0 100965.0 103795.0 18702.0 18807.0 19384.1 19461.0 19480.2 35235.5 35253.0 35328.8 35409.5 35519.8 9995.5 10007.2 10011.3 10020.0 10128.4 8114.4 8116.8 8146.3 8286.5 8381.1 9227.3 9266.2 9278.3 9534.2 9616.9 3396.6 3546.0 3551.1 3574.5 3575.9 951.3 1007.1 1059.1 1060.9 1076.4 19598.0 20094.4 20117.6 20350.2 20560.2 1021.7 1029.6 1042.9 1082.4 1089.5 10617.8 11330.0 11381.7 11407.4 11425.8 500295.0 502833.0 509856.0 510941.0 513405.0 100531.5 101730.5 102932.0 103022.8 104914.5 1123.9 1170.3 1175.7 1189.2 1201.1 17722.9 18062.4 18189.9 18387.0 18460.7 45474.8 46981.0 47113.0 47230.1 48130.8 63183.7 64192.0 64875.7 66915.0 67210.3 8371.4 8395.5 8843.2 9284.9 9566.7 10060.5 12697.5 12800.6 13110.2 13649.7 11561.4 11686.9 12061.5 12373.2 12500.7 11251.4 11641.5 11875.7 11918.8 12528.1 10871.7 10925.2 11087.5 11489.9 12555.6 8794.9 8863.6 8963.4 9207.8 9429.6 10101.9 10190.0 11149.7 11173.3 11249.6 22:20:39 INFO - PROCESS | 10150 | 22:20:39 INFO - PROCESS | 10150 | [#3] mootools.html Cycles:180 Average:6237.07 Median:4854.10 stddev:3616.58 (74.5%) stddev-sans-first:3608.64 22:20:39 INFO - PROCESS | 10150 | Values: 1424.9 1555.1 1586.0 1740.6 1876.7 8377.3 9017.9 9329.5 9413.7 9429.7 8913.2 9123.5 9134.0 9322.8 9325.3 2496.9 2543.6 2543.9 2545.5 2547.2 2460.0 2530.9 2531.4 2531.8 2532.1 2488.6 2501.6 2528.6 2532.7 2536.1 2461.5 2468.8 2473.5 2508.7 2513.6 2428.9 2436.9 2441.9 2442.1 2482.5 9326.5 9634.9 9672.7 9714.7 9736.9 5624.4 5875.1 5890.1 5890.9 5897.6 3659.2 3676.2 3735.3 3744.6 3749.3 1240.5 1245.9 1249.4 1281.6 1284.4 12622.9 12850.0 12853.5 12869.9 12882.2 4872.1 4889.8 4983.7 5059.9 5061.3 11228.9 11242.7 11255.2 11268.4 11275.4 5707.1 5716.1 5717.3 5721.8 5723.3 4525.6 4579.7 4643.3 4836.1 5214.8 4376.4 4437.8 4530.8 4546.7 4552.5 1375.7 1402.4 1412.0 1421.8 1424.4 997.4 1003.8 1016.8 1020.3 1022.3 6077.7 6089.9 6147.9 6213.4 6365.7 498.3 501.4 508.8 511.2 514.6 4147.5 4263.5 4288.4 4302.2 4370.8 11857.6 12310.8 12351.8 12368.3 12372.1 11951.2 12255.3 12279.1 12306.6 12336.1 9021.7 9179.5 9209.9 9216.9 9225.6 6157.2 6158.9 6205.0 6226.0 6307.9 10914.7 10986.3 10990.0 11329.2 11345.9 9672.4 9785.2 11237.3 11430.7 11519.5 8391.8 8656.1 8756.0 8756.3 8759.1 9362.6 9378.5 9438.5 9446.3 9449.0 8257.2 8473.2 8637.1 8792.1 8837.0 8003.1 8093.7 8128.7 8266.9 8379.9 7340.2 7379.6 7393.4 7442.1 7487.3 4948.5 4951.0 5034.0 5090.4 5129.2 5254.2 5274.3 5299.7 5358.6 5365.8 22:20:39 INFO - PROCESS | 10150 | 22:20:39 INFO - PROCESS | 10150 | [#4] prototype.html Cycles:180 Average:4823.96 Median:5448.20 stddev:1393.65 (25.6%) stddev-sans-first:1356.52 22:20:39 INFO - PROCESS | 10150 | Values: 350.8 358.4 365.1 368.4 369.3 5315.4 5434.3 5461.0 5525.1 5536.4 5182.8 5334.8 5386.1 5390.2 5404.7 4327.3 4335.8 4341.7 4344.7 4372.8 4206.7 4324.7 4355.0 4365.0 4368.3 4293.9 4315.4 4321.5 4321.8 4346.6 4298.2 4298.8 4304.1 4327.5 4339.7 3653.3 3797.8 3800.6 3813.7 3831.8 5265.7 5278.4 5280.3 5291.0 5295.9 4899.7 4902.5 4939.3 4946.7 4970.6 5494.9 5665.3 5668.3 5668.4 5673.1 4116.7 4128.3 4142.7 4146.7 4149.5 5756.5 5876.0 5928.9 5947.5 5974.8 4220.1 4229.3 4235.6 4288.4 4299.0 4339.8 4344.9 4345.0 4347.4 4350.4 4887.7 4913.4 4915.2 4923.2 4946.4 5368.9 5591.7 5626.7 5679.0 5701.7 1413.2 1433.9 1485.9 1494.6 1494.8 1009.0 1016.1 1033.9 1036.6 1043.4 5443.0 5453.4 5465.2 5465.9 5473.2 5708.4 5723.1 5756.3 5759.7 5810.1 5257.6 5537.9 5562.2 5567.7 5597.4 3082.3 3087.5 3095.7 3096.7 3117.3 5772.6 6052.4 6070.8 6100.5 6102.6 5975.7 5979.0 5997.0 5999.7 6013.5 6037.8 6049.4 6056.8 6060.8 6075.6 5616.7 5820.9 5855.2 5863.6 5880.6 5753.4 5762.0 5773.7 5843.8 5855.0 5835.7 6070.2 6083.0 6093.1 6104.5 4198.3 4216.6 4239.4 4250.4 4256.2 5669.9 5849.7 5856.2 5913.3 5922.8 5387.7 5702.4 5717.7 5760.9 5775.3 5679.0 5728.8 5736.6 5739.5 5743.9 5550.8 5612.6 5615.3 5618.7 5665.3 5708.1 5727.4 5736.9 5754.9 5773.5 5482.3 5593.4 5625.3 5634.3 5640.0 22:20:39 INFO - PROCESS | 10150 | 22:20:39 INFO - PROCESS | 10150 | [#5] yui.html Cycles:180 Average:21601.20 Median:4754.76 stddev:30481.10 (641.1%) stddev-sans-first:30526.53 22:20:39 INFO - PROCESS | 10150 | Values: 786.2 795.5 850.2 879.8 928.5 16366.8 16400.3 16504.3 16610.3 16778.4 16127.8 16131.9 16146.4 16262.5 16297.3 719.0 737.0 833.5 853.5 875.4 865.2 868.0 869.1 869.2 869.8 919.7 921.1 921.6 922.5 922.8 756.8 775.0 888.1 903.0 914.8 664.7 665.7 666.0 666.3 666.4 15006.8 15057.4 15065.1 15073.8 15076.9 15190.2 15663.5 15693.2 15824.8 16176.2 14122.5 14224.8 14298.7 14640.0 14679.4 4695.3 4725.2 4784.3 4820.5 4908.9 87375.6 87454.6 87529.6 87601.0 88089.6 12450.0 12519.2 12541.3 12549.7 12550.2 23141.5 23204.8 23351.2 23356.8 23956.9 8537.7 8823.6 8841.8 8849.3 9141.2 5073.7 5140.2 5161.8 5214.5 5294.7 3783.9 3923.7 3948.2 3948.7 3955.6 681.6 686.2 695.0 715.9 724.4 719.3 747.6 765.6 777.7 797.7 9490.7 9788.2 10072.9 10076.3 10220.1 661.6 689.3 755.4 764.8 771.0 4646.2 4945.5 4956.2 4977.5 4986.8 74359.3 75421.0 75645.0 106593.5 115787.4 112534.4 115622.4 117574.0 117635.0 117963.4 71140.6 89561.0 98100.5 98465.5 101312.5 9442.3 9480.1 9488.5 9544.7 9739.8 44908.1 45499.0 49726.8 58365.4 58658.4 51854.2 65995.7 80019.8 84908.6 85658.6 14375.3 14416.5 14597.6 14653.6 14797.7 15825.0 16077.0 16272.3 16288.3 16679.0 14536.6 14546.3 14941.3 15111.8 15215.2 13537.7 14143.3 14339.5 15000.8 15556.1 13746.7 14439.1 14599.4 14855.7 15041.4 11919.8 12077.3 12735.7 12762.7 13139.0 10808.6 11282.3 12664.7 12743.7 12931.0 22:20:39 INFO - PROCESS | 10150 | -------- Summary: end -------- 22:20:39 INFO - PROCESS | 10150 | 22:20:39 INFO - TEST-INFO | 10150: exit 0 22:20:39 INFO - TEST-OK | dromaeo_css | took 1113933ms 22:20:39 INFO - TEST-START | kraken 22:20:39 INFO - operating with platform_type : linux_ 22:20:39 INFO - Initialising browser for kraken test... 22:20:40 INFO - TEST-INFO | started process 10470 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV9tBR6/profile http://localhost:46044/getInfo.html) 22:20:44 INFO - PROCESS | 10470 | __metrics Screen width/height:1600/1200 22:20:44 INFO - PROCESS | 10470 | colorDepth:24 22:20:44 INFO - PROCESS | 10470 | Browser inner width/height: 1024/697 22:20:44 INFO - PROCESS | 10470 | __metrics 22:20:44 INFO - PROCESS | 10470 | 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" 22:20:44 INFO - PROCESS | 10470 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:20:45 INFO - PROCESS | 10470 | console.error: PushService: 22:20:45 INFO - PROCESS | 10470 | stateChangeProcessEnqueue: Error transitioning state 22:20:45 INFO - PROCESS | 10470 | UnknownError 22:20:45 INFO - TEST-INFO | 10470: exit 0 22:20:45 INFO - Browser initialized. 22:20:45 INFO - Running cycle 1/1 for kraken test... 22:20:45 INFO - TEST-INFO | started process 10560 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV9tBR6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:57 INFO - PROCESS | 10560 | RSS: Main: 148185088 22:20:57 INFO - PROCESS | 10560 | 22:21:00 INFO - PROCESS | 10560 | Cycle 1(1): loaded http://localhost:46044/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46044/tests/kraken/driver.html?testName=audio-beat-detection) 22:21:00 INFO - PROCESS | 10560 | RSS: Main: 190484480 22:21:00 INFO - PROCESS | 10560 | 22:21:03 INFO - PROCESS | 10560 | Cycle 1(1): loaded http://localhost:46044/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46044/tests/kraken/driver.html?testName=audio-dft) 22:21:04 INFO - PROCESS | 10560 | RSS: Main: 179740672 22:21:04 INFO - PROCESS | 10560 | 22:21:06 INFO - PROCESS | 10560 | Cycle 1(1): loaded http://localhost:46044/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46044/tests/kraken/driver.html?testName=audio-fft) 22:21:07 INFO - PROCESS | 10560 | RSS: Main: 271265792 22:21:07 INFO - PROCESS | 10560 | 22:21:09 INFO - PROCESS | 10560 | Cycle 1(1): loaded http://localhost:46044/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46044/tests/kraken/driver.html?testName=audio-oscillator) 22:21:10 INFO - PROCESS | 10560 | RSS: Main: 177307648 22:21:10 INFO - PROCESS | 10560 | 22:21:12 INFO - PROCESS | 10560 | Cycle 1(1): loaded http://localhost:46044/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46044/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:21:12 INFO - PROCESS | 10560 | RSS: Main: 178900992 22:21:12 INFO - PROCESS | 10560 | 22:21:15 INFO - PROCESS | 10560 | Cycle 1(1): loaded http://localhost:46044/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46044/tests/kraken/driver.html?testName=imaging-darkroom) 22:21:16 INFO - PROCESS | 10560 | RSS: Main: 215498752 22:21:16 INFO - PROCESS | 10560 | 22:21:19 INFO - PROCESS | 10560 | Cycle 1(1): loaded http://localhost:46044/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46044/tests/kraken/driver.html?testName=imaging-desaturate) 22:21:20 INFO - PROCESS | 10560 | RSS: Main: 214155264 22:21:20 INFO - PROCESS | 10560 | 22:21:22 INFO - PROCESS | 10560 | Cycle 1(1): loaded http://localhost:46044/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46044/tests/kraken/driver.html?testName=json-parse-financial) 22:21:23 INFO - PROCESS | 10560 | RSS: Main: 213000192 22:21:23 INFO - PROCESS | 10560 | 22:21:25 INFO - PROCESS | 10560 | Cycle 1(1): loaded http://localhost:46044/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46044/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:21:25 INFO - PROCESS | 10560 | RSS: Main: 175538176 22:21:25 INFO - PROCESS | 10560 | 22:21:27 INFO - PROCESS | 10560 | Cycle 1(1): loaded http://localhost:46044/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46044/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:21:28 INFO - PROCESS | 10560 | RSS: Main: 183709696 22:21:28 INFO - PROCESS | 10560 | 22:21:29 INFO - PROCESS | 10560 | Cycle 1(1): loaded http://localhost:46044/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46044/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:21:30 INFO - PROCESS | 10560 | RSS: Main: 189837312 22:21:30 INFO - PROCESS | 10560 | 22:21:33 INFO - PROCESS | 10560 | Cycle 1(1): loaded http://localhost:46044/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46044/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:21:33 INFO - PROCESS | 10560 | RSS: Main: 197263360 22:21:33 INFO - PROCESS | 10560 | 22:21:36 INFO - PROCESS | 10560 | Cycle 1(1): loaded http://localhost:46044/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46044/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:21:36 INFO - PROCESS | 10560 | RSS: Main: 194183168 22:21:36 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | Cycle 1(1): loaded http://localhost:46044/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46044/tests/kraken/driver.html?testName=ai-astar) 22:21:38 INFO - PROCESS | 10560 | RSS: Main: 245207040 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | __start_tp_report 22:21:38 INFO - PROCESS | 10560 | _x_x_mozilla_page_load 22:21:38 INFO - PROCESS | 10560 | _x_x_mozilla_page_load_details 22:21:38 INFO - PROCESS | 10560 | |i|pagename|runs| 22:21:38 INFO - PROCESS | 10560 | |0;ai-astar;109;110;111;111;112;111;111;110;109;111 22:21:38 INFO - PROCESS | 10560 | |1;audio-beat-detection;144;119;117;150;123;122;118;122;160;123 22:21:38 INFO - PROCESS | 10560 | |2;audio-dft;161;161;160;160;162;160;161;159;159;159 22:21:38 INFO - PROCESS | 10560 | |3;audio-fft;74;76;76;111;76;76;75;78;106;84 22:21:38 INFO - PROCESS | 10560 | |4;audio-oscillator;82;82;110;81;81;82;99;81;82;102 22:21:38 INFO - PROCESS | 10560 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;116 22:21:38 INFO - PROCESS | 10560 | |6;imaging-darkroom;147;146;145;146;147;145;147;146;145;146 22:21:38 INFO - PROCESS | 10560 | |7;imaging-desaturate;95;94;96;94;95;95;95;95;95;95 22:21:38 INFO - PROCESS | 10560 | |8;json-parse-financial;51;51;53;57;51;51;52;51;50;51 22:21:38 INFO - PROCESS | 10560 | |9;json-stringify-tinderbox;58;60;58;59;59;59;58;60;59;59 22:21:38 INFO - PROCESS | 10560 | |10;stanford-crypto-aes;76;73;81;77;73;73;72;72;72;84 22:21:38 INFO - PROCESS | 10560 | |11;stanford-crypto-ccm;124;125;126;129;121;129;119;119;166;131 22:21:38 INFO - PROCESS | 10560 | |12;stanford-crypto-pbkdf2;151;151;150;149;152;153;151;158;151;154 22:21:38 INFO - PROCESS | 10560 | |13;stanford-crypto-sha256-iterative;63;61;64;62;60;60;64;61;59;60 22:21:38 INFO - PROCESS | 10560 | __end_tp_report 22:21:38 INFO - PROCESS | 10560 | __start_cc_report 22:21:38 INFO - PROCESS | 10560 | _x_x_mozilla_cycle_collect,1570 22:21:38 INFO - PROCESS | 10560 | __end_cc_report 22:21:38 INFO - PROCESS | 10560 | __startTimestamp1479104498868__endTimestamp 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | ------- Summary: start ------- 22:21:38 INFO - PROCESS | 10560 | Number of tests: 14 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:0.97 (0.9%) stddev-sans-first:0.87 22:21:38 INFO - PROCESS | 10560 | Values: 109.0 110.0 111.0 111.0 112.0 111.0 111.0 110.0 109.0 111.0 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:123.00 stddev:15.48 (12.6%) stddev-sans-first:15.54 22:21:38 INFO - PROCESS | 10560 | Values: 144.0 119.0 117.0 150.0 123.0 122.0 118.0 122.0 160.0 123.0 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.50 stddev:1.03 (0.6%) stddev-sans-first:1.05 22:21:38 INFO - PROCESS | 10560 | Values: 161.0 161.0 160.0 160.0 162.0 160.0 161.0 159.0 159.0 159.0 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | [#3] audio-fft Cycles:10 Average:83.20 Median:76.00 stddev:13.66 (18.0%) stddev-sans-first:14.08 22:21:38 INFO - PROCESS | 10560 | Values: 74.0 76.0 76.0 111.0 76.0 76.0 75.0 78.0 106.0 84.0 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | [#4] audio-oscillator Cycles:10 Average:88.20 Median:82.00 stddev:11.01 (13.4%) stddev-sans-first:11.45 22:21:38 INFO - PROCESS | 10560 | Values: 82.0 82.0 110.0 81.0 81.0 82.0 99.0 81.0 82.0 102.0 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:21:38 INFO - PROCESS | 10560 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 22:21:38 INFO - PROCESS | 10560 | Values: 147.0 146.0 145.0 146.0 147.0 145.0 147.0 146.0 145.0 146.0 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:21:38 INFO - PROCESS | 10560 | Values: 95.0 94.0 96.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:51.00 stddev:1.99 (3.9%) stddev-sans-first:2.09 22:21:38 INFO - PROCESS | 10560 | Values: 51.0 51.0 53.0 57.0 51.0 51.0 52.0 51.0 50.0 51.0 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.00 stddev:0.74 (1.3%) stddev-sans-first:0.71 22:21:38 INFO - PROCESS | 10560 | Values: 58.0 60.0 58.0 59.0 59.0 59.0 58.0 60.0 59.0 59.0 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | [#10] stanford-crypto-aes Cycles:10 Average:75.30 Median:74.50 stddev:4.22 (5.7%) stddev-sans-first:4.47 22:21:38 INFO - PROCESS | 10560 | Values: 76.0 73.0 81.0 77.0 73.0 73.0 72.0 72.0 72.0 84.0 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | [#11] stanford-crypto-ccm Cycles:10 Average:128.90 Median:127.50 stddev:13.69 (10.7%) stddev-sans-first:14.41 22:21:38 INFO - PROCESS | 10560 | Values: 124.0 125.0 126.0 129.0 121.0 129.0 119.0 119.0 166.0 131.0 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.00 Median:151.50 stddev:2.54 (1.7%) stddev-sans-first:2.67 22:21:38 INFO - PROCESS | 10560 | Values: 151.0 151.0 150.0 149.0 152.0 153.0 151.0 158.0 151.0 154.0 22:21:38 INFO - PROCESS | 10560 | 22:21:38 INFO - PROCESS | 10560 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:61.50 stddev:1.78 (2.9%) stddev-sans-first:1.79 22:21:38 INFO - PROCESS | 10560 | Values: 63.0 61.0 64.0 62.0 60.0 60.0 64.0 61.0 59.0 60.0 22:21:38 INFO - PROCESS | 10560 | -------- Summary: end -------- 22:21:38 INFO - PROCESS | 10560 | 22:21:39 INFO - TEST-INFO | 10560: exit 0 22:21:39 INFO - TEST-OK | kraken | took 59888ms 22:21:39 INFO - SUITE-END | took 1173s 22:21:40 INFO - Completed test suite (00:19:33) 22:21:40 INFO - javascript benchmark 22:21:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3168.8732450701973, 3211.42194405007, 3226.9677303226977, 3243.675632436755, 3257.6905194006563, 5615.915761263579, 5699.173619825133, 5703.914441283378, 5731.226284451608, 5764.30828300604, 5658.688772117543, 5722.341930677973, 5725.7995970141055, 5726.0552008918585, 5728.112142617901, 5159.922601160967, 5165.586753059765, 5171.560417364529, 5195.636305458619, 5233.162693968966, 5100.311457953185, 5249.291345668324, 5263.131583288753, 5263.289455923439, 5294.4176140624695, 5122.129238029525, 5125.282460455539, 5128.076946149691, 5172.27588137661, 5187.740612969355, 5177.430482646924, 5278.340207474066, 5279.1289437243, 5280.128778751501, 5280.471952804727, 5140.023395554833, 5179.404368497596, 5246.580273578104, 5256.159014557691, 5274.472552744734, 5482.945170548323, 5542.2795036644975, 5566.471185237396, 5585.944140558583, 5672.40439753824, 6017.699115044274, 6106.694665266719, 6140.846478838065, 6142.6314421134875, 6147.969260153715, 6150.261968563756, 6167.41409566095, 6171.382861713838, 6174.536909731788, 6174.691265436711, 5531.059719847635, 5569.10894256917, 5624.100143976985, 5696.886062278814, 5698.8290351289525, 6262.655553944577, 6282.780102696383, 6285.43431091204, 6288.591241569284, 6303.054541818672, 6032.095185722089, 6033.849153771191, 6034.366391528914, 6036.275646922398, 6039.4262545058145, 5767.653940763567, 5827.0, 6004.7598096076545, 6099.390060993954, 6126.111713801563, 6107.917136652965, 6114.049973376061, 6119.080741749068, 6129.059628562994, 6129.450483694044, 6115.571909966348, 6140.3552626974415, 6151.72317245725, 6158.137860699506, 6163.907541386883, 4492.730436173774, 4524.25349817283, 4531.682782205214, 4685.508021657677, 4715.528447155257, 4077.327241005201, 4098.241825262336, 4107.096438783463, 4139.130782535641, 4146.771927543954, 5739.081746920556, 5743.396943320891, 5745.310562732415, 5749.223854779635, 5815.563832712563, 5947.197128387699, 6006.039033754579, 6051.0, 6052.606580572248, 6055.485283750931, 5700.971495142498, 5751.194832723422, 5763.971180144073, 5846.824595262149, 5882.705864706748, 5264.736763161826, 5294.708791016531, 5301.469853014668, 5309.123994540934, 5310.522053015247, 6165.290991536031, 6296.5277604178955, 6338.049292606146, 6344.6827658616885, 6356.555041147168, 6059.6364218147055, 6116.143739876332, 6122.877542449215, 6125.264968203844, 6194.690265486798, 6388.329225431263, 6394.936050639434, 6396.232452105777, 6400.0, 6404.0, 6063.060225665029, 6071.180390647382, 6074.878502430015, 6080.48315893154, 6084.0, 5867.677277749594, 5922.851928701817, 5992.250968628921, 6001.669908155093, 6015.338312785502, 5904.439078287556, 5919.200907877554, 5919.526437885045, 5920.674362910081, 5928.673922934107, 5912.763489460545, 5924.022536281465, 5924.7926322578505, 5926.733297001731, 5931.970340148444, 5655.293088363955, 5788.855278618068, 5836.358000620014, 5870.412958704095, 5873.442023007808, 6035.728392222272, 6039.607425517327, 6043.244594425697, 6055.939440605537, 6085.269767627913, 5707.514861236841, 5735.598508104393, 5778.191053252632, 5779.566532510163, 5784.623999440023], "value": 5608.239072361246, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.8604427959428, 619.6096459230685, 635.4535099814162, 655.9147310849589, 658.749675123453, 34370.0, 38324.0, 38386.99999999996, 45600.0, 47266.29100563494, 46893.7655311723, 47180.056398872, 47290.0, 47486.52513474864, 47513.287300690514, 9278.118578735026, 9434.716958491255, 9434.716958491255, 9439.622415103388, 9446.1026202511, 8438.493690378566, 8590.312774978018, 8638.093000234947, 8792.912070879305, 9150.588223529958, 10927.0, 11181.608643697473, 11227.101831853473, 11568.826467602992, 11573.247738896987, 11169.0, 11229.213955023151, 11249.887501125007, 11255.437228138642, 11256.887431125666, 11023.393713345762, 11028.117750580015, 11051.115910727123, 11091.778164436746, 11092.389918554476, 44369.334459982805, 44707.55292447066, 44724.552754472366, 44759.552404475864, 44804.0, 3748.194138260287, 4282.400463935051, 4460.821567137311, 4538.863834084983, 4597.885052873671, 33606.32787344264, 33662.83168584166, 33987.49018764721, 34599.30801383958, 35565.28869422623, 4647.9767601161775, 4673.065386922629, 4702.553257440538, 4710.199266124767, 4891.070696567641, 254660.0, 254901.0, 255728.99999999814, 256385.0, 257598.0, 38721.22557548833, 38879.0, 59922.0, 60307.396926031055, 60603.69698151569, 41635.791821041305, 42087.789561052, 42094.78952605279, 42099.158016839494, 42108.0, 16358.263878125514, 16514.42199523011, 16523.33906643721, 16525.752113718303, 16529.504114876574, 13640.590782276548, 13718.725625487434, 13734.587962361145, 13737.931310343385, 13738.793918091236, 8950.194482496476, 9101.954490227507, 9105.089491050841, 9573.808523829624, 9597.113530819886, 3555.2711694102645, 3651.1419816343137, 3668.6147954464395, 3684.0, 3776.3202623528027, 1043.1341986151476, 1051.574112484445, 1057.3942854865027, 1058.5606973106076, 1137.886211378872, 34198.35163467065, 34529.0, 34743.4788478173, 34775.0, 34779.30441391158, 1155.5839897636845, 1169.181572898974, 1173.0439691651277, 1192.66605350502, 1201.963941081769, 17817.821821781876, 18193.7270940936, 18194.363197288032, 18203.362882299058, 18375.72436413455, 606535.0, 613528.0, 614051.0, 616120.0, 617766.0, 55088.17367739487, 55833.0, 61393.386066138766, 61762.382376175665, 61860.38139618636, 1354.959351219465, 1393.9721205575831, 1394.1774353131498, 1430.4135304525303, 1434.0463591711602, 33610.83194584012, 35257.29485410329, 35420.291594168484, 35504.64495355013, 35697.46453803143, 53195.0, 53673.0, 53699.0, 53793.462065378844, 53795.0, 408230.0, 526908.0, 542499.0, 542530.0, 866636.0, 45725.54274457213, 45741.771291143334, 45822.54177458316, 46069.0, 46242.53757462382, 51318.23022654738, 52090.47909520856, 52392.0, 52397.21404178864, 52562.737186313825, 42213.0, 42839.14321713611, 42911.356329655704, 43493.565064350216, 44230.0, 43234.783826081926, 43763.12473750571, 43780.34329485124, 44079.77960110179, 44946.550534494236, 43145.784271078446, 43676.126477470905, 44478.0, 44635.553644463136, 44656.33015504705, 35712.642873570934, 38502.00624164044, 38832.6116738829, 39104.41343379908, 39575.40636890391, 29041.530379621538, 29114.56328155037, 29199.0, 29706.0, 29724.851375742983], "value": 20561.55452935921, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.1358708396322, 412.75853625629003, 428.939948407223, 434.09274616052454, 436.75323442255143, 13085.476580936775, 14405.423783048678, 19681.0, 19681.507962300948, 19717.0, 21681.3495595132, 22167.889160554216, 22833.200837970675, 22846.428839279026, 22997.655035174485, 6457.096006559085, 6567.408933196011, 6613.074169616233, 6645.867082658344, 6666.966665166691, 6374.872502549946, 6558.1802274715665, 6655.500837437187, 6655.600663960154, 6657.733690652368, 6539.64031978241, 6583.835404114888, 6585.308542603029, 6585.374389432996, 6585.835354116138, 6388.968055159718, 6571.671416429183, 6582.440492558138, 6584.670766461658, 6697.464202863759, 6455.096286519891, 6506.60960342381, 6521.021846722982, 6523.739050437976, 6529.053287273318, 25470.10854620098, 26162.6075608866, 26307.605385919225, 27688.584671229946, 27954.860225698943, 2520.5841036229, 2613.451175253199, 2620.842749435021, 2648.2187754612437, 3274.4597141471627, 15417.614559635987, 16826.579335516588, 17047.48857534276, 17085.231164597622, 17710.29158833645, 2138.454694053022, 2190.8575942563843, 2207.0399376271375, 2267.841251112406, 2310.8266879984067, 95590.0, 99540.0, 99742.0, 100965.0, 103795.0, 18702.0, 18807.0, 19384.127714252892, 19461.0, 19480.220791168478, 35235.471467928, 35253.0, 35328.823355883054, 35409.46885796715, 35519.82240088835, 9995.500224988722, 10007.199424046059, 10011.349262297927, 10020.0, 10128.442935638463, 8114.391420643476, 8116.797080072927, 8146.348292136615, 8286.46138001028, 8381.07808141104, 9227.308313595757, 9266.212371948324, 9278.257739380833, 9534.237261019101, 9616.9038309616, 3396.626371099177, 3546.0142320300715, 3551.1477245460906, 3574.5148480232087, 3575.8927232183078, 951.2627713521938, 1007.0785231513083, 1059.0944742245424, 1060.8992145746142, 1076.359566058194, 19598.0, 20094.39716808498, 20117.597648046958, 20350.18599256013, 20560.177592896118, 1021.7496713305288, 1029.624187171677, 1042.8957104289661, 1082.3830416662427, 1089.455272363818, 10617.84073238902, 11330.0, 11381.715457113638, 11407.372594507216, 11425.828612570818, 500295.0, 502833.0, 509856.0, 510941.0, 513405.0, 100531.49734251283, 101730.49134754279, 102932.0, 103022.79707358425, 104914.4754276239, 1123.859517560305, 1170.2861254634665, 1175.6893031950185, 1189.2270024483983, 1201.1351826684772, 17722.91138544299, 18062.36781712634, 18189.909050454662, 18387.0, 18460.723089153405, 45474.772626136655, 46981.0, 47113.0, 47230.05539889252, 48130.75934620304, 63183.6840815793, 64192.0, 64875.67562162159, 66915.0, 67210.32789672041, 8371.41400101987, 8395.538245396561, 8843.204111629984, 9284.860727088964, 9566.712998610054, 10060.49697515136, 12697.492100315883, 12800.551980680631, 13110.213387196798, 13649.658758530719, 11561.42192890369, 11686.94156529246, 12061.51753929833, 12373.195742276723, 12500.749985000431, 11251.437428128724, 11641.476133574179, 11875.703107422383, 11918.761624767629, 12528.060395470195, 10871.728206794576, 10925.235233533576, 11087.501062452047, 11489.942550287195, 12555.623331300076, 8794.868076978722, 8863.64545418176, 8963.372563920462, 9207.76980575491, 9429.57566909477, 10101.89898101039, 10190.0, 11149.665510034712, 11173.273737207055, 11249.606263780728], "value": 10779.261328658145, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.935877885495, 1555.1291276884942, 1585.9841401585984, 1740.6170642458655, 1876.6622008038548, 8377.329813614911, 9017.909820901788, 9329.486878221694, 9413.717588472357, 9429.717108486739, 8913.153250441213, 9123.452592844435, 9133.954330228356, 9322.813543729122, 9325.300602454809, 2496.900123995038, 2543.618457231421, 2543.872806359684, 2545.5163518931345, 2547.1849008317254, 2459.963100553493, 2530.9493810123786, 2531.392465808211, 2531.8101142414303, 2532.126416386353, 2488.6267059940974, 2501.6497690323367, 2528.6207068939716, 2532.708738495069, 2536.1250368622796, 2461.52000359929, 2468.8024958003316, 2473.468204336067, 2508.749125087495, 2513.572692642237, 2428.9392765180837, 2436.8781560921884, 2441.853488790678, 2442.120836498859, 2482.453860150764, 9326.48704321262, 9634.855477167848, 9672.70981870545, 9714.708558743248, 9736.902630973742, 5624.409437009137, 5875.059990401515, 5890.087036509348, 5890.941090589082, 5897.616654917417, 3659.1949771050326, 3676.1544844685604, 3735.346314394997, 3744.6255374462335, 3749.325121478133, 1240.5347994502063, 1245.906557008219, 1249.4065318973505, 1281.5642681488246, 1284.3514025417105, 12622.873771262353, 12850.036247281398, 12853.485860565566, 12869.871301286961, 12882.1626594272, 4872.1473742094995, 4889.755512224375, 4983.704236898432, 5059.949400505948, 5061.316722242524, 11228.887711122947, 11242.66272011841, 11255.155863310285, 11268.436578171222, 11275.37985410794, 5707.0583353747, 5716.056850619601, 5717.285339332583, 5721.771129154788, 5723.341815691166, 4525.570070843265, 4579.748113853703, 4643.257078867365, 4836.056968891033, 5214.765335559909, 4376.40918476008, 4437.844675436409, 4530.796114174804, 4546.704464209815, 4552.544745525421, 1375.7454870848926, 1402.4390243902317, 1411.978820317696, 1421.779624158257, 1424.3875133692404, 997.436448406648, 1003.8193125237527, 1016.761061150629, 1020.2959957629212, 1022.2997246885907, 6077.726502307407, 6089.908651370233, 6147.907781383283, 6213.409726076015, 6365.681715914186, 498.3197934818946, 501.41835470853636, 508.8041104174919, 511.2076281763252, 514.6320380927655, 4147.543770185277, 4263.5096963849555, 4288.4210631564965, 4302.22559939214, 4370.825166993284, 11857.64427067172, 12310.75378492425, 12351.814722779165, 12368.257904525757, 12372.072094592762, 11951.22317049389, 12255.264684118982, 12279.140460167702, 12306.569270075504, 12336.136470447162, 9021.684241051531, 9179.495127768037, 9209.861852072225, 9216.861747073799, 9225.58484868169, 6157.199564056644, 6158.938410615837, 6205.0, 6226.0, 6307.936920630735, 10914.672559823219, 10986.285891417032, 10990.0, 11329.206955513035, 11345.943270283597, 9672.419654820733, 9785.168260697921, 11237.269577477438, 11430.657080287605, 11519.481623326803, 8391.832163356821, 8656.091110433314, 8756.0, 8756.343274254328, 8759.080296569024, 9362.625494980124, 9378.531073446436, 9438.48088355147, 9446.338756286994, 9448.95275523618, 8257.21556452136, 8473.195046470575, 8637.093105223861, 8792.120787921413, 8837.0, 8003.11965683786, 8093.676252949816, 8128.715494957648, 8266.875996859932, 8379.874301885355, 7340.192578816219, 7379.631018449163, 7393.371563417169, 7442.144153422426, 7487.3261406473675, 4948.529889660476, 4950.950490495049, 5034.0, 5090.41460232078, 5129.230615407719, 5254.211868219798, 5274.261603375608, 5299.735013249245, 5358.571314294925, 5365.839024829262], "value": 4895.199919049085, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.83510749947516, 358.3996805351037, 365.0745360511105, 368.43997124370975, 369.2964901861954, 5315.39545622645, 5434.266374039514, 5460.972695136524, 5525.088360420531, 5536.4186760390185, 5182.807954170543, 5334.786608535664, 5386.111291636876, 5390.245365648812, 5404.729763511828, 4327.329263964075, 4335.783210839445, 4341.674374421915, 4344.717593356421, 4372.803223854934, 4206.737978606421, 4324.718893271943, 4355.0, 4364.9781751091205, 4368.322909948963, 4293.914121717564, 4315.41741864849, 4321.524632290446, 4321.783910804463, 4346.587074227943, 4298.247806633826, 4298.785060746965, 4304.117655880537, 4327.480702315711, 4339.696221264513, 3653.30587188436, 3797.829097690583, 3800.619938006205, 3813.694904407641, 3831.8467261309515, 5265.736713164365, 5278.445763194848, 5280.313559237313, 5290.989424018887, 5295.894082118374, 4899.730514821684, 4902.480231128362, 4939.259111133323, 4946.653734238605, 4970.552650261494, 5494.917576236359, 5665.3201615806365, 5668.348139963916, 5668.43315668434, 5673.12066629669, 4116.670666346685, 4128.298189307825, 4142.689298302609, 4146.688998325108, 4149.522804877417, 5756.532084318514, 5875.970620146914, 5928.911066334009, 5947.494462970652, 5974.790882319098, 4220.134872351161, 4229.302165142779, 4235.5552666969525, 4288.4210631564965, 4299.032717638506, 4339.7830108494445, 4344.869653910388, 4345.000649850489, 4347.413099231627, 4350.434443522385, 4887.65786394956, 4913.385826771653, 4915.21356582945, 4923.236898280772, 4946.431160416598, 5368.91946620801, 5591.692456914828, 5626.718664066781, 5678.971605141948, 5701.657900525982, 1413.187417235094, 1433.8637829406189, 1485.851414858527, 1494.5815171751926, 1494.796688665613, 1008.9646862359929, 1016.1362841584594, 1033.9172866170688, 1036.5957276662107, 1043.3531210649446, 5443.0, 5453.400125986218, 5465.234867118606, 5465.863353416196, 5473.17902314656, 5708.429157084258, 5723.112917497711, 5756.33802112754, 5759.740811663485, 5810.12848072784, 5257.63196576236, 5537.944620553823, 5562.22128901946, 5567.66594004361, 5597.384287728347, 3082.2602575382193, 3087.5368694695526, 3095.6998060814512, 3096.690330966885, 3117.314190878003, 5772.624779389326, 6052.368578852741, 6070.757169713251, 6100.54245931548, 6102.603330783485, 5975.731092100866, 5978.983572792636, 5997.0, 5999.670018148957, 6013.54898382623, 6037.78867739627, 6049.425304596057, 6056.818295451144, 6060.848478787977, 6075.635461872125, 5616.66300022, 5820.883582328245, 5855.180274761452, 5863.648181109147, 5880.617759845596, 5753.395893431297, 5761.971190144022, 5773.653580785166, 5843.795467158629, 5854.975379308689, 5835.708214589169, 6070.2412198475195, 6082.969585152223, 6093.130861902635, 6104.542159338156, 4198.349255865346, 4216.566367435087, 4239.4488716467895, 4250.447441832542, 4256.212600668886, 5669.914951275817, 5849.736761845642, 5856.159762515262, 5913.2608423947, 5922.763089476372, 5387.676739395649, 5702.40124786908, 5717.742701578356, 5760.94239057604, 5775.27809023872, 5679.0, 5728.825590753802, 5736.627119237236, 5739.512141468022, 5743.8851222976145, 5550.7510810067415, 5612.607117501736, 5615.2700148982, 5618.719064046863, 5665.348484924287, 5708.115242137475, 5727.3940357138235, 5736.856578585569, 5754.913676294943, 5773.498890288472, 5482.30048684909, 5593.448160418083, 5625.315418400954, 5634.267545219095, 5640.0], "value": 4387.651533536767, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.2137862137862, 795.4670370851529, 850.2262940723939, 879.7976465412959, 928.5403725156049, 16366.83633163668, 16400.343986240536, 16504.339826406944, 16610.335586576522, 16778.41275555356, 16127.758083628754, 16131.91934040331, 16146.434874779385, 16262.51212463625, 16297.266623001964, 718.9964050179742, 737.0124033794714, 833.5248908122358, 853.4751128056232, 875.3653601139187, 865.2040123086744, 868.0191383736401, 869.074435725953, 869.2220462685892, 869.8042940338437, 919.7332773495712, 921.0835218957147, 921.5760750054983, 922.524899676667, 922.7508572685371, 756.7691853984533, 774.9845003099935, 888.1074520107284, 903.0472851142042, 914.7713071732067, 664.7141729056503, 665.7137430904709, 666.0176240045942, 666.2671061831994, 666.4335314982275, 15006.774898376532, 15057.397704091823, 15065.096094234272, 15073.773893391608, 15076.924615376962, 15190.177178738511, 15663.530094097081, 15693.215339232993, 15824.76262856058, 16176.191190440548, 14122.505712300122, 14224.786628200585, 14298.714025719531, 14640.0, 14679.412823487048, 4695.3426520287185, 4725.220338644154, 4784.306275590055, 4820.469748327716, 4908.9263661045115, 87375.56312218399, 87454.5627271866, 87529.56235218783, 87601.0, 88089.55955220183, 12450.0, 12519.248845069325, 12541.31022793755, 12549.749005019848, 12550.1842380245, 23141.53716925652, 23204.7679523206, 23351.182708605458, 23356.7664323358, 23956.88021559881, 8537.74386768398, 8823.647054117891, 8841.823163536694, 8849.292056635453, 9141.177294043435, 5073.695578265316, 5140.177571588528, 5161.845144645667, 5214.45248248936, 5294.682319060868, 3783.8675646352244, 3923.7449565778134, 3948.2498325318097, 3948.7235893487755, 3955.564887862333, 681.6012632609879, 686.1560280854577, 695.006141218063, 715.8675645005691, 724.3625609463744, 719.2807192807193, 747.5888261456178, 765.5674543882689, 777.7433446962489, 797.6809276289415, 9490.667826626035, 9788.21694264457, 10072.949635251776, 10076.294659373774, 10220.131288840383, 661.6162625677192, 689.3416786968434, 755.3730403764972, 764.7629234937076, 771.0246538129271, 4646.163690535736, 4945.530174633404, 4956.207006878882, 4977.527134922177, 4986.800527978913, 74359.25640743523, 75421.0, 75645.0, 106593.4670326659, 115787.42106289415, 112534.43732781448, 115622.42188789003, 117574.0, 117635.0, 117963.41018294853, 71140.64429677922, 89561.0, 98100.50949745349, 98465.50767246119, 101312.49343753382, 9442.291828112782, 9480.146786789222, 9488.48262158898, 9544.665936692183, 9739.756506087404, 44908.10183796371, 45499.0, 49726.751366242934, 58365.416345836, 58658.413415865296, 51854.22218666648, 65995.6700216515, 80019.79970300567, 84908.57545712232, 85658.57170714106, 14375.28123593837, 14416.495422660157, 14597.635059123606, 14653.633659158606, 14797.704045919236, 15825.0, 16077.0, 16272.267747951133, 16288.267027983533, 16679.0, 14536.636584085483, 14546.345414456164, 14941.252937353307, 15111.773323400377, 15215.239238038275, 13537.72924541484, 14143.292835358397, 14339.498117565836, 15000.774988374966, 15556.144412056992, 13746.725065498435, 14439.061461005002, 14599.416023359372, 14855.702885942437, 15041.398344066114, 11919.82120268214, 12077.335746533674, 12735.745285094432, 12762.744745105232, 13139.0, 10808.56765729362, 11282.323060616389, 12664.6833829155, 12743.74512509726, 12931.0], "value": 8076.348711780269, "unit": "score"}], "name": "dromaeo_css", "value": 7743.824184808425, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 110.0, 111.0, 111.0, 112.0, 111.0, 111.0, 110.0, 109.0, 111.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 119.0, 117.0, 150.0, 123.0, 122.0, 118.0, 122.0, 160.0, 123.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 162.0, 160.0, 161.0, 159.0, 159.0, 159.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 111.0, 76.0, 76.0, 75.0, 78.0, 106.0, 84.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 110.0, 81.0, 81.0, 82.0, 99.0, 81.0, 82.0, 102.0], "value": 88.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, 117.0, 117.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 145.0, 146.0, 147.0, 145.0, 147.0, 146.0, 145.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 53.0, 57.0, 51.0, 51.0, 52.0, 51.0, 50.0, 51.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 58.0, 59.0, 59.0, 59.0, 58.0, 60.0, 59.0, 59.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 81.0, 77.0, 73.0, 73.0, 72.0, 72.0, 72.0, 84.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 125.0, 126.0, 129.0, 121.0, 129.0, 119.0, 119.0, 166.0, 131.0], "value": 128.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 150.0, 149.0, 152.0, 153.0, 151.0, 158.0, 151.0, 154.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 64.0, 62.0, 60.0, 60.0, 64.0, 61.0, 59.0, 60.0], "value": 61.4, "unit": "score"}], "name": "kraken", "value": 1458.0, "alertThreshold": 2.0}]} 22:21:40 INFO - Return code: 0 22:21:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:21:40 INFO - # TBPL SUCCESS # 22:21:40 INFO - Running post-action listener: _resource_record_post_action 22:21:40 INFO - [mozharness: 2016-11-14 06:21:40.185454Z] Finished run-tests step (success) 22:21:40 INFO - Running post-run listener: _resource_record_post_run 22:21:40 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 373542912; Read time: 12; Write time: 304892 22:21:40 INFO - TinderboxPrint: CPU usage
12.9% 22:21:40 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 22:21:40 INFO - TinderboxPrint: I/O write bytes / time
373,542,912 / 304,892 22:21:40 INFO - TinderboxPrint: CPU idle
8,270.5 (87.1%) 22:21:40 INFO - TinderboxPrint: CPU user
1,183.7 (12.5%) 22:21:40 INFO - TinderboxPrint: Swap in / out
0 / 0 22:21:40 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 124260352; Read time: 0; Write time: 110100 22:21:40 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 249282560; Read time: 0; Write time: 194792 22:21:40 INFO - Running post-run listener: _upload_blobber_files 22:21:40 INFO - Blob upload gear active. 22:21:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:21:40 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 22:21:40 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'] 22:21:40 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 22:21:41 INFO - (blobuploader) - INFO - Open directory for files ... 22:21:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:21:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:21:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:42 INFO - (blobuploader) - INFO - Done attempting. 22:21:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:21:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:21:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:45 INFO - (blobuploader) - INFO - Done attempting. 22:21:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:21:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:21:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:46 INFO - (blobuploader) - INFO - Done attempting. 22:21:46 INFO - (blobuploader) - INFO - Iteration through files over. 22:21:46 INFO - Return code: 0 22:21:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:21:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:21:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0db7bf9d6e1bd8285b81012507a18a5b79126a6e737b3ae3de2d49d63f732b7e15e7f509531aeea6bb74674c15ebee1c875f975d56618c08ff72c6c71ba63c3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a508caa963cbdf6d2f9862026fdf1069f4de74b14dfcee0107f57178cd134eee2a470f97d7b54094830771fc136ac7a39cefacd8515d5bf2dc194402fc2c71b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fff6e25495f9aba7bd51fa5e948e8df6fce6d7ee7b91ad3887b8e4f388e43c4fda213c5ece358d252595342194001b9f27eff7e909402900dfe45bda52dea74a"} 22:21:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:21:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:21:46 INFO - Contents: 22:21:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0db7bf9d6e1bd8285b81012507a18a5b79126a6e737b3ae3de2d49d63f732b7e15e7f509531aeea6bb74674c15ebee1c875f975d56618c08ff72c6c71ba63c3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a508caa963cbdf6d2f9862026fdf1069f4de74b14dfcee0107f57178cd134eee2a470f97d7b54094830771fc136ac7a39cefacd8515d5bf2dc194402fc2c71b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fff6e25495f9aba7bd51fa5e948e8df6fce6d7ee7b91ad3887b8e4f388e43c4fda213c5ece358d252595342194001b9f27eff7e909402900dfe45bda52dea74a"} 22:21:46 INFO - Running post-run listener: copy_logs_to_upload_dir 22:21:46 INFO - Copying logs to upload dir... 22:21:46 INFO - mkdir: /builds/slave/test/build/upload/logs 22:21:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.727126 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-11-13 22:21:45.471894) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-13 22:21:45.475045) ========= 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=836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490 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/0db7bf9d6e1bd8285b81012507a18a5b79126a6e737b3ae3de2d49d63f732b7e15e7f509531aeea6bb74674c15ebee1c875f975d56618c08ff72c6c71ba63c3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a508caa963cbdf6d2f9862026fdf1069f4de74b14dfcee0107f57178cd134eee2a470f97d7b54094830771fc136ac7a39cefacd8515d5bf2dc194402fc2c71b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fff6e25495f9aba7bd51fa5e948e8df6fce6d7ee7b91ad3887b8e4f388e43c4fda213c5ece358d252595342194001b9f27eff7e909402900dfe45bda52dea74a"} build_url:https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011428 build_url: 'https://queue.taskcluster.net/v1/task/Erc5-v4bS1KSIgDr078BxA/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/0db7bf9d6e1bd8285b81012507a18a5b79126a6e737b3ae3de2d49d63f732b7e15e7f509531aeea6bb74674c15ebee1c875f975d56618c08ff72c6c71ba63c3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a508caa963cbdf6d2f9862026fdf1069f4de74b14dfcee0107f57178cd134eee2a470f97d7b54094830771fc136ac7a39cefacd8515d5bf2dc194402fc2c71b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fff6e25495f9aba7bd51fa5e948e8df6fce6d7ee7b91ad3887b8e4f388e43c4fda213c5ece358d252595342194001b9f27eff7e909402900dfe45bda52dea74a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-13 22:21:45.502369) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 22:21:45.502953) ========= 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=836cfdc75598d54cb747368e000001a9-1479102011.392217-640643490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-13 22:21:45.588069) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-13 22:21:45.588484) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-13 22:21:45.588851) ========= ========= Total master_lag: 0.19 =========