builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-045 starttime: 1472043531.09 results: success (0) buildid: 20160824030337 builduid: c5f9edaf64374c3f9f1b9947a91d9334 revision: bd7645928990649c84609d3f531e803c2d41f269 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-24 05:58:51.093672) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-24 05:58:51.094125) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-24 05:58:51.094407) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-24 05:58:51.130017) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 05:58:51.130315) ========= 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=4300f881736b422476174874000001bc-1472042438.804563-876286220 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-24 05:58:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-08-24 05:58:51 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116362 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 05:58:51.262509) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 05:58:51.262854) ========= 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=4300f881736b422476174874000001bc-1472042438.804563-876286220 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017892 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 05:58:51.313565) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 05:58:51.313941) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bd7645928990649c84609d3f531e803c2d41f269 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev bd7645928990649c84609d3f531e803c2d41f269 --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=4300f881736b422476174874000001bc-1472042438.804563-876286220 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-24 05:58:51,875 truncating revision to first 12 chars 2016-08-24 05:58:51,875 Setting DEBUG logging. 2016-08-24 05:58:51,875 attempt 1/10 2016-08-24 05:58:51,875 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bd7645928990?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-24 05:58:52,129 unpacking tar archive at: mozilla-central-bd7645928990/testing/mozharness/ program finished with exit code 0 elapsedTime=0.544733 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 05:58:51.874780) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-24 05:58:51.875298) ========= script_repo_revision: bd7645928990649c84609d3f531e803c2d41f269 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-24 05:58:51.875949) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-24 05:58:51.876445) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-24 05:58:51.887596) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-08-24 05:58:51.887876) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1472042438.804563-876286220 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:58:52 INFO - MultiFileLogger online at 20160824 05:58:52 in /builds/slave/test 05:58:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:58:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:58:52 INFO - {'append_to_log': False, 05:58:52 INFO - 'base_work_dir': '/builds/slave/test', 05:58:52 INFO - 'blob_upload_branch': 'Firefox', 05:58:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:58:52 INFO - 'branch': 'Firefox', 05:58:52 INFO - 'buildbot_json_path': 'buildprops.json', 05:58:52 INFO - 'config_files': ('talos/linux_config.py',), 05:58:52 INFO - 'default_actions': ('clobber', 05:58:52 INFO - 'read-buildbot-config', 05:58:52 INFO - 'download-and-extract', 05:58:52 INFO - 'populate-webroot', 05:58:52 INFO - 'create-virtualenv', 05:58:52 INFO - 'install', 05:58:52 INFO - 'run-tests'), 05:58:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:58:52 INFO - 'download_minidump_stackwalk': True, 05:58:52 INFO - 'download_symbols': 'ondemand', 05:58:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:58:52 INFO - 'tooltool.py': '/tools/tooltool.py', 05:58:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:58:52 INFO - '/tools/misc-python/virtualenv.py')}, 05:58:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:58:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:58:52 INFO - 'installer_path': 'installer.exe', 05:58:52 INFO - 'log_level': 'info', 05:58:52 INFO - 'log_name': 'talos', 05:58:52 INFO - 'log_to_console': True, 05:58:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:58:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:58:52 INFO - 'opt_config_files': (), 05:58:52 INFO - 'pip_index': False, 05:58:52 INFO - 'sps_profile': False, 05:58:52 INFO - 'sps_profile_interval': 0, 05:58:52 INFO - 'suite': 'dromaeojs', 05:58:52 INFO - 'system_bits': '32', 05:58:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:58:52 INFO - 'title': 'talos-linux64-ix-045', 05:58:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:58:52 INFO - 'use_talos_json': True, 05:58:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:58:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:58:52 INFO - 'work_dir': 'build'} 05:58:52 INFO - [mozharness: 2016-08-24 12:58:52.547219Z] Running clobber step. 05:58:52 INFO - Running pre-action listener: _resource_record_pre_action 05:58:52 INFO - Running main action method: clobber 05:58:52 INFO - rmtree: /builds/slave/test/build 05:58:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:58:53 INFO - Running post-action listener: _resource_record_post_action 05:58:53 INFO - [mozharness: 2016-08-24 12:58:53.008821Z] Finished clobber step (success) 05:58:53 INFO - [mozharness: 2016-08-24 12:58:53.008923Z] Running read-buildbot-config step. 05:58:53 INFO - Running pre-action listener: _resource_record_pre_action 05:58:53 INFO - Running main action method: read_buildbot_config 05:58:53 INFO - Using buildbot properties: 05:58:53 INFO - { 05:58:53 INFO - "project": "", 05:58:53 INFO - "product": "firefox", 05:58:53 INFO - "script_repo_revision": "production", 05:58:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:58:53 INFO - "repository": "", 05:58:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:58:53 INFO - "buildid": "20160824030337", 05:58:53 INFO - "stage_platform": "linux64-pgo", 05:58:53 INFO - "basedir": "/builds/slave/test", 05:58:53 INFO - "nightly_build": "True", 05:58:53 INFO - "buildnumber": 542, 05:58:53 INFO - "slavename": "talos-linux64-ix-045", 05:58:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:58:53 INFO - "platform": "ubuntu64_hw", 05:58:53 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:58:53 INFO - "branch": "mozilla-central", 05:58:53 INFO - "repo_path": "mozilla-central", 05:58:53 INFO - "revision": "bd7645928990649c84609d3f531e803c2d41f269", 05:58:53 INFO - "pgo_build": "True", 05:58:53 INFO - "builduid": "c5f9edaf64374c3f9f1b9947a91d9334", 05:58:53 INFO - "slavebuilddir": "test" 05:58:53 INFO - } 05:58:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 05:58:53 INFO - Running post-action listener: _resource_record_post_action 05:58:53 INFO - [mozharness: 2016-08-24 12:58:53.011423Z] Finished read-buildbot-config step (success) 05:58:53 INFO - [mozharness: 2016-08-24 12:58:53.011511Z] Running download-and-extract step. 05:58:53 INFO - Running pre-action listener: _resource_record_pre_action 05:58:53 INFO - Running main action method: download_and_extract 05:58:53 INFO - mkdir: /builds/slave/test/build/tests 05:58:53 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')]} 05:58:53 INFO - trying https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:58:53 INFO - Downloading https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:58:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:58:54 INFO - Downloaded 1361 bytes. 05:58:54 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:58:54 INFO - Using the following test package requirements: 05:58:54 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:58:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:58:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:58:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:54 INFO - u'jsshell-linux-x86_64.zip'], 05:58:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:58:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:58:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:58:54 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:58:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:58:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:58:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:58:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:58:54 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')]} 05:58:54 INFO - trying https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:58:54 INFO - Downloading https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:58:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:58:55 INFO - Downloaded 21915048 bytes. 05:58:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 05:58:59 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:58:59 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')]} 05:58:59 INFO - trying https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:58:59 INFO - Downloading https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:58:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:59:00 INFO - Downloaded 21915048 bytes. 05:59:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 05:59:04 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')]} 05:59:04 INFO - trying https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:59:04 INFO - Downloading https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:59:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:59:05 INFO - Downloaded 14413981 bytes. 05:59:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 05:59: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')]} 05:59:05 INFO - trying https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:59:05 INFO - Downloading https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:59:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:59:06 INFO - Downloaded 59743230 bytes. 05:59:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:59:06 INFO - mkdir: /builds/slave/test/properties 05:59:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:59:06 INFO - Writing to file /builds/slave/test/properties/build_url 05:59:06 INFO - Contents: 05:59:06 INFO - build_url:https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:59:07 INFO - Running post-action listener: _resource_record_post_action 05:59:07 INFO - Running post-action listener: set_extra_try_arguments 05:59:07 INFO - [mozharness: 2016-08-24 12:59:07.439917Z] Finished download-and-extract step (success) 05:59:07 INFO - [mozharness: 2016-08-24 12:59:07.440142Z] Running populate-webroot step. 05:59:07 INFO - Running pre-action listener: _resource_record_pre_action 05:59:07 INFO - Running main action method: populate_webroot 05:59:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:59:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:59:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:59:07 INFO - '--tppagecycles', 05:59:07 INFO - '7'], 05:59:07 INFO - 'tests': ['tsvgm']}}, 05:59:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:59:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:59:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:59:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:59:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:59:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:59:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:07 INFO - 'talos_options': ['--disable-e10s'], 05:59:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:59:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:59:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:07 INFO - 'talos_options': ['--disable-e10s'], 05:59:07 INFO - 'tests': ['damp', 'tps']}, 05:59:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:07 INFO - 'tests': ['damp', 'tps']}, 05:59:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:59:07 INFO - 'tests': ['dromaeo_dom']}, 05:59:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:59:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:59:07 INFO - 'tests': ['basic_compositor_video']}, 05:59:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:59:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:59:07 INFO - 'tests': ['a11yr', 05:59:07 INFO - 'ts_paint', 05:59:07 INFO - 'tpaint', 05:59:07 INFO - 'sessionrestore', 05:59:07 INFO - 'sessionrestore_no_auto_restore', 05:59:07 INFO - 'tabpaint']}, 05:59:07 INFO - 'other-e10s': {'tests': ['a11yr', 05:59:07 INFO - 'ts_paint', 05:59:07 INFO - 'tpaint', 05:59:07 INFO - 'sessionrestore', 05:59:07 INFO - 'sessionrestore_no_auto_restore', 05:59:07 INFO - 'tabpaint']}, 05:59:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:59:07 INFO - 'ts_paint', 05:59:07 INFO - 'tpaint', 05:59:07 INFO - 'sessionrestore', 05:59:07 INFO - 'sessionrestore_no_auto_restore', 05:59:07 INFO - 'tabpaint']}, 05:59:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:59:07 INFO - 'ts_paint', 05:59:07 INFO - 'tpaint', 05:59:07 INFO - 'sessionrestore', 05:59:07 INFO - 'sessionrestore_no_auto_restore', 05:59:07 INFO - 'tabpaint']}, 05:59:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:59:07 INFO - 'tests': ['a11yr', 05:59:07 INFO - 'ts_paint', 05:59:07 INFO - 'tpaint', 05:59:07 INFO - 'sessionrestore', 05:59:07 INFO - 'sessionrestore_no_auto_restore', 05:59:07 INFO - 'tabpaint']}, 05:59:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:59:07 INFO - 'tests': ['a11yr', 05:59:07 INFO - 'ts_paint', 05:59:07 INFO - 'tpaint', 05:59:07 INFO - 'sessionrestore', 05:59:07 INFO - 'sessionrestore_no_auto_restore', 05:59:07 INFO - 'tabpaint']}, 05:59:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:59:07 INFO - 'tests': ['tsvgx', 05:59:07 INFO - 'tsvgr_opacity', 05:59:07 INFO - 'tart', 05:59:07 INFO - 'tscrollx', 05:59:07 INFO - 'cart']}, 05:59:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:59:07 INFO - 'tsvgr_opacity', 05:59:07 INFO - 'tart', 05:59:07 INFO - 'tscrollx', 05:59:07 INFO - 'cart']}, 05:59:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:07 INFO - 'talos_options': ['--disable-e10s'], 05:59:07 INFO - 'tests': ['tp5o']}, 05:59:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:07 INFO - 'tests': ['tp5o']}, 05:59:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:07 INFO - 'talos_options': ['--disable-e10s', 05:59:07 INFO - '--xperf_path', 05:59:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:59:07 INFO - 'tests': ['tp5n']}, 05:59:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:07 INFO - 'talos_options': ['--xperf_path', 05:59:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:59:07 INFO - 'tests': ['tp5n']}}, 05:59:07 INFO - 'talos.zip': {'path': '', 05:59:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:59:07 INFO - Running post-action listener: _resource_record_post_action 05:59:07 INFO - [mozharness: 2016-08-24 12:59:07.459404Z] Finished populate-webroot step (success) 05:59:07 INFO - [mozharness: 2016-08-24 12:59:07.459492Z] Running create-virtualenv step. 05:59:07 INFO - Running pre-action listener: _resource_record_pre_action 05:59:07 INFO - Running main action method: create_virtualenv 05:59:07 INFO - Creating virtualenv /builds/slave/test/build/venv 05:59:07 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 05:59:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:59:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:59:07 INFO - Using real prefix '/usr' 05:59:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:59:08 INFO - Installing distribute.............................................................................................................................................................................................done. 05:59:10 INFO - Installing pip.................done. 05:59:10 INFO - Return code: 0 05:59:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:59:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:10 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 0x7fc841d67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8370>, '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 0x27bb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bbd80>, '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': '4300f881736b422476174874000001bc-1472042438.804563-876286220', '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 05:59:10 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 05:59:10 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 05:59:10 INFO - Using env: {'DISPLAY': ':0', 05:59:10 INFO - 'HOME': '/home/cltbld', 05:59:10 INFO - 'LANG': 'en_US.UTF-8', 05:59:10 INFO - 'LANGUAGE': 'en_US:en', 05:59:10 INFO - 'LOGNAME': 'cltbld', 05:59:10 INFO - 'MAIL': '/var/mail/cltbld', 05:59:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:10 INFO - 'MOZ_NO_REMOTE': '1', 05:59:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:10 INFO - 'NO_EM_RESTART': '1', 05:59:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:10 INFO - 'PWD': '/builds/slave/test', 05:59:10 INFO - 'SHELL': '/bin/bash', 05:59:10 INFO - 'SHLVL': '1', 05:59:10 INFO - 'TERM': 'linux', 05:59:10 INFO - 'TMOUT': '86400', 05:59:10 INFO - 'USER': 'cltbld', 05:59:10 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1472042438.804563-876286220', 05:59:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:10 INFO - '_': '/tools/buildbot/bin/python'} 05:59:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:10 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 05:59:10 INFO - Cleaning up... 05:59:10 INFO - Return code: 0 05:59:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:59:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:10 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 0x7fc841d67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8370>, '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 0x27bb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bbd80>, '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': '4300f881736b422476174874000001bc-1472042438.804563-876286220', '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 05:59:10 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 05:59:10 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 05:59:10 INFO - Using env: {'DISPLAY': ':0', 05:59:10 INFO - 'HOME': '/home/cltbld', 05:59:10 INFO - 'LANG': 'en_US.UTF-8', 05:59:10 INFO - 'LANGUAGE': 'en_US:en', 05:59:10 INFO - 'LOGNAME': 'cltbld', 05:59:10 INFO - 'MAIL': '/var/mail/cltbld', 05:59:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:10 INFO - 'MOZ_NO_REMOTE': '1', 05:59:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:10 INFO - 'NO_EM_RESTART': '1', 05:59:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:10 INFO - 'PWD': '/builds/slave/test', 05:59:10 INFO - 'SHELL': '/bin/bash', 05:59:10 INFO - 'SHLVL': '1', 05:59:10 INFO - 'TERM': 'linux', 05:59:10 INFO - 'TMOUT': '86400', 05:59:10 INFO - 'USER': 'cltbld', 05:59:10 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1472042438.804563-876286220', 05:59:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:10 INFO - '_': '/tools/buildbot/bin/python'} 05:59:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:10 INFO - Downloading/unpacking psutil>=3.1.1 05:59:10 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 05:59:10 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 05:59:10 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 05:59:10 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 05:59:10 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 05:59:10 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 05:59:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:59:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:59:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:59:12 INFO - Installing collected packages: psutil 05:59:12 INFO - Running setup.py install for psutil 05:59:12 INFO - building 'psutil._psutil_linux' extension 05:59:12 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 05:59:12 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 05:59:12 INFO - building 'psutil._psutil_posix' extension 05:59:12 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 05:59:12 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 05:59:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:59:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:59:12 INFO - Successfully installed psutil 05:59:12 INFO - Cleaning up... 05:59:13 INFO - Return code: 0 05:59:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:59: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')]} 05:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59: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')]} 05:59:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:13 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 0x7fc841d67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8370>, '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 0x27bb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bbd80>, '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': '4300f881736b422476174874000001bc-1472042438.804563-876286220', '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 05:59:13 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 05:59:13 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 05:59:13 INFO - Using env: {'DISPLAY': ':0', 05:59:13 INFO - 'HOME': '/home/cltbld', 05:59:13 INFO - 'LANG': 'en_US.UTF-8', 05:59:13 INFO - 'LANGUAGE': 'en_US:en', 05:59:13 INFO - 'LOGNAME': 'cltbld', 05:59:13 INFO - 'MAIL': '/var/mail/cltbld', 05:59:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:13 INFO - 'MOZ_NO_REMOTE': '1', 05:59:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:13 INFO - 'NO_EM_RESTART': '1', 05:59:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:13 INFO - 'PWD': '/builds/slave/test', 05:59:13 INFO - 'SHELL': '/bin/bash', 05:59:13 INFO - 'SHLVL': '1', 05:59:13 INFO - 'TERM': 'linux', 05:59:13 INFO - 'TMOUT': '86400', 05:59:13 INFO - 'USER': 'cltbld', 05:59:13 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1472042438.804563-876286220', 05:59:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:13 INFO - '_': '/tools/buildbot/bin/python'} 05:59:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:59:13 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 05:59:13 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 05:59:13 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 05:59:13 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 05:59:13 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 05:59:13 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 05:59:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:59:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:59:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:59:15 INFO - Installing collected packages: mozsystemmonitor 05:59:15 INFO - Running setup.py install for mozsystemmonitor 05:59:15 INFO - Successfully installed mozsystemmonitor 05:59:15 INFO - Cleaning up... 05:59:15 INFO - Return code: 0 05:59:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:59:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc841d67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8370>, '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 0x27bb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bbd80>, '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': '4300f881736b422476174874000001bc-1472042438.804563-876286220', '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 05:59:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:59:15 INFO - Using env: {'DISPLAY': ':0', 05:59:15 INFO - 'HOME': '/home/cltbld', 05:59:15 INFO - 'LANG': 'en_US.UTF-8', 05:59:15 INFO - 'LANGUAGE': 'en_US:en', 05:59:15 INFO - 'LOGNAME': 'cltbld', 05:59:15 INFO - 'MAIL': '/var/mail/cltbld', 05:59:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:15 INFO - 'MOZ_NO_REMOTE': '1', 05:59:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:15 INFO - 'NO_EM_RESTART': '1', 05:59:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:15 INFO - 'PWD': '/builds/slave/test', 05:59:15 INFO - 'SHELL': '/bin/bash', 05:59:15 INFO - 'SHLVL': '1', 05:59:15 INFO - 'TERM': 'linux', 05:59:15 INFO - 'TMOUT': '86400', 05:59:15 INFO - 'USER': 'cltbld', 05:59:15 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1472042438.804563-876286220', 05:59:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:15 INFO - '_': '/tools/buildbot/bin/python'} 05:59:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:15 INFO - Downloading/unpacking jsonschema==2.5.1 05:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:59:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:59:17 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 05:59:17 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 05:59:17 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 05:59:17 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 05:59:17 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 05:59:17 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 05:59:17 INFO - Downloading functools32-3.2.3-2.tar.gz 05:59:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:59:17 INFO - warning: no files found matching '*.txt' 05:59:17 INFO - no previously-included directories found matching 'build' 05:59:17 INFO - no previously-included directories found matching 'dist' 05:59:17 INFO - no previously-included directories found matching '.git*' 05:59:17 INFO - Installing collected packages: jsonschema, functools32 05:59:17 INFO - Running setup.py install for functools32 05:59:17 INFO - warning: no files found matching '*.txt' 05:59:17 INFO - no previously-included directories found matching 'build' 05:59:17 INFO - no previously-included directories found matching 'dist' 05:59:17 INFO - no previously-included directories found matching '.git*' 05:59:17 INFO - Successfully installed jsonschema functools32 05:59:17 INFO - Cleaning up... 05:59:17 INFO - Return code: 0 05:59:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc841d67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8370>, '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 0x27bb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bbd80>, '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': '4300f881736b422476174874000001bc-1472042438.804563-876286220', '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 05:59:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:59:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:59:17 INFO - Using env: {'DISPLAY': ':0', 05:59:17 INFO - 'HOME': '/home/cltbld', 05:59:17 INFO - 'LANG': 'en_US.UTF-8', 05:59:17 INFO - 'LANGUAGE': 'en_US:en', 05:59:17 INFO - 'LOGNAME': 'cltbld', 05:59:17 INFO - 'MAIL': '/var/mail/cltbld', 05:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:17 INFO - 'MOZ_NO_REMOTE': '1', 05:59:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:17 INFO - 'NO_EM_RESTART': '1', 05:59:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:17 INFO - 'PWD': '/builds/slave/test', 05:59:17 INFO - 'SHELL': '/bin/bash', 05:59:17 INFO - 'SHLVL': '1', 05:59:17 INFO - 'TERM': 'linux', 05:59:17 INFO - 'TMOUT': '86400', 05:59:17 INFO - 'USER': 'cltbld', 05:59:17 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1472042438.804563-876286220', 05:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:17 INFO - '_': '/tools/buildbot/bin/python'} 05:59:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:59:18 INFO - Cleaning up... 05:59:18 INFO - Return code: 0 05:59:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:59:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc841d67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8370>, '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 0x27bb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bbd80>, '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': '4300f881736b422476174874000001bc-1472042438.804563-876286220', '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 05:59:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:59:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:59:18 INFO - Using env: {'DISPLAY': ':0', 05:59:18 INFO - 'HOME': '/home/cltbld', 05:59:18 INFO - 'LANG': 'en_US.UTF-8', 05:59:18 INFO - 'LANGUAGE': 'en_US:en', 05:59:18 INFO - 'LOGNAME': 'cltbld', 05:59:18 INFO - 'MAIL': '/var/mail/cltbld', 05:59:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:18 INFO - 'MOZ_NO_REMOTE': '1', 05:59:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:18 INFO - 'NO_EM_RESTART': '1', 05:59:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:18 INFO - 'PWD': '/builds/slave/test', 05:59:18 INFO - 'SHELL': '/bin/bash', 05:59:18 INFO - 'SHLVL': '1', 05:59:18 INFO - 'TERM': 'linux', 05:59:18 INFO - 'TMOUT': '86400', 05:59:18 INFO - 'USER': 'cltbld', 05:59:18 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1472042438.804563-876286220', 05:59:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:18 INFO - '_': '/tools/buildbot/bin/python'} 05:59:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:18 INFO - Downloading/unpacking blobuploader==1.2.4 05:59:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:20 INFO - Downloading blobuploader-1.2.4.tar.gz 05:59:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:59:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:59:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:21 INFO - Downloading docopt-0.6.1.tar.gz 05:59:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:59:21 INFO - Installing collected packages: blobuploader, requests, docopt 05:59:21 INFO - Running setup.py install for blobuploader 05:59:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:59:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:59:21 INFO - Running setup.py install for requests 05:59:21 INFO - Running setup.py install for docopt 05:59:21 INFO - Successfully installed blobuploader requests docopt 05:59:21 INFO - Cleaning up... 05:59:21 INFO - Return code: 0 05:59:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:21 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 0x7fc841d67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8370>, '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 0x27bb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bbd80>, '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': '4300f881736b422476174874000001bc-1472042438.804563-876286220', '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 05:59:21 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 05:59:21 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 05:59:21 INFO - Using env: {'DISPLAY': ':0', 05:59:21 INFO - 'HOME': '/home/cltbld', 05:59:21 INFO - 'LANG': 'en_US.UTF-8', 05:59:21 INFO - 'LANGUAGE': 'en_US:en', 05:59:21 INFO - 'LOGNAME': 'cltbld', 05:59:21 INFO - 'MAIL': '/var/mail/cltbld', 05:59:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:21 INFO - 'MOZ_NO_REMOTE': '1', 05:59:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:21 INFO - 'NO_EM_RESTART': '1', 05:59:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:21 INFO - 'PWD': '/builds/slave/test', 05:59:21 INFO - 'SHELL': '/bin/bash', 05:59:21 INFO - 'SHLVL': '1', 05:59:21 INFO - 'TERM': 'linux', 05:59:21 INFO - 'TMOUT': '86400', 05:59:21 INFO - 'USER': 'cltbld', 05:59:21 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1472042438.804563-876286220', 05:59:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:21 INFO - '_': '/tools/buildbot/bin/python'} 05:59:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:59:21 INFO - Running setup.py (path:/tmp/pip-CrP7mz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:59:22 INFO - Running setup.py (path:/tmp/pip-htdGpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:59:22 INFO - Running setup.py (path:/tmp/pip-K3dPZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:59:22 INFO - Running setup.py (path:/tmp/pip-eof6Yk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:59:22 INFO - Running setup.py (path:/tmp/pip-OA0Dtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:59:22 INFO - Running setup.py (path:/tmp/pip-D88dWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:59:22 INFO - Running setup.py (path:/tmp/pip-C42DMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:59:22 INFO - Running setup.py (path:/tmp/pip-yy9vof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:59:22 INFO - Running setup.py (path:/tmp/pip-s8myUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:59:22 INFO - Running setup.py (path:/tmp/pip-EGW5cP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:59:22 INFO - Running setup.py (path:/tmp/pip-qIFRqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:59:22 INFO - Running setup.py (path:/tmp/pip-H8FQOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:59:22 INFO - Running setup.py (path:/tmp/pip-iBxbKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:59:22 INFO - Running setup.py (path:/tmp/pip-VoPvMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:23 INFO - Running setup.py (path:/tmp/pip-gQaZw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:59:23 INFO - Running setup.py (path:/tmp/pip-EzbnTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:59:23 INFO - Running setup.py (path:/tmp/pip-PJvdPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:59:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:59:23 INFO - Running setup.py install for manifestparser 05:59:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:59:23 INFO - Running setup.py install for mozcrash 05:59:23 INFO - Running setup.py install for mozdebug 05:59:23 INFO - Running setup.py install for mozdevice 05:59:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:59:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:59:23 INFO - Running setup.py install for mozfile 05:59:23 INFO - Running setup.py install for mozhttpd 05:59:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:59:23 INFO - Running setup.py install for mozinfo 05:59:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:59:23 INFO - Running setup.py install for mozInstall 05:59:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:59:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:59:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:59:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:59:24 INFO - Running setup.py install for mozleak 05:59:24 INFO - Running setup.py install for mozlog 05:59:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:59:24 INFO - Running setup.py install for moznetwork 05:59:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:59:24 INFO - Running setup.py install for mozprocess 05:59:24 INFO - Running setup.py install for mozprofile 05:59:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:59:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:59:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:59:24 INFO - Running setup.py install for mozrunner 05:59:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:59:24 INFO - Running setup.py install for mozscreenshot 05:59:24 INFO - Running setup.py install for moztest 05:59:24 INFO - Running setup.py install for mozversion 05:59:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:59:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:59:24 INFO - Cleaning up... 05:59:25 INFO - Return code: 0 05:59:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59: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')]} 05:59:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59: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')]} 05:59:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:25 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 0x7fc841d67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8370>, '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 0x27bb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bbd80>, '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': '4300f881736b422476174874000001bc-1472042438.804563-876286220', '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 05:59:25 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 05:59:25 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 05:59:25 INFO - Using env: {'DISPLAY': ':0', 05:59:25 INFO - 'HOME': '/home/cltbld', 05:59:25 INFO - 'LANG': 'en_US.UTF-8', 05:59:25 INFO - 'LANGUAGE': 'en_US:en', 05:59:25 INFO - 'LOGNAME': 'cltbld', 05:59:25 INFO - 'MAIL': '/var/mail/cltbld', 05:59:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:25 INFO - 'MOZ_NO_REMOTE': '1', 05:59:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:25 INFO - 'NO_EM_RESTART': '1', 05:59:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:25 INFO - 'PWD': '/builds/slave/test', 05:59:25 INFO - 'SHELL': '/bin/bash', 05:59:25 INFO - 'SHLVL': '1', 05:59:25 INFO - 'TERM': 'linux', 05:59:25 INFO - 'TMOUT': '86400', 05:59:25 INFO - 'USER': 'cltbld', 05:59:25 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1472042438.804563-876286220', 05:59:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:25 INFO - '_': '/tools/buildbot/bin/python'} 05:59:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:59:25 INFO - Running setup.py (path:/tmp/pip-iPK_7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:59:25 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)) 05:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:59:25 INFO - Running setup.py (path:/tmp/pip-9dVedV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:59:25 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)) 05:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:59:25 INFO - Running setup.py (path:/tmp/pip-bfF2U3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:59:25 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)) 05:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:59:25 INFO - Running setup.py (path:/tmp/pip-7sY_NO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:59:25 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)) 05:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:59:25 INFO - Running setup.py (path:/tmp/pip-fZaqGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:59:25 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)) 05:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:59:25 INFO - Running setup.py (path:/tmp/pip-n4dWU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:59:25 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)) 05:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:59:25 INFO - Running setup.py (path:/tmp/pip-zlLUAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:59:25 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)) 05:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:59:25 INFO - Running setup.py (path:/tmp/pip-EcnrmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:59:25 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)) 05:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:59:25 INFO - Running setup.py (path:/tmp/pip-R9Q9Tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:59:25 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)) 05:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:59:25 INFO - Running setup.py (path:/tmp/pip-ymRrmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:59:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 05:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:59:25 INFO - Running setup.py (path:/tmp/pip-WXY50Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:59:26 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)) 05:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:59:26 INFO - Running setup.py (path:/tmp/pip-1Vvtze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:59:26 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)) 05:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:59:26 INFO - Running setup.py (path:/tmp/pip-JQVxWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:59:26 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)) 05:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:59:26 INFO - Running setup.py (path:/tmp/pip-cJfivv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:59:26 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)) 05:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:26 INFO - Running setup.py (path:/tmp/pip-O1HwyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:26 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)) 05:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:59:26 INFO - Running setup.py (path:/tmp/pip-ChYp0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:59:26 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)) 05:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:59:26 INFO - Running setup.py (path:/tmp/pip-lHQ91n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:59:26 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)) 05:59:26 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)) 05:59:26 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)) 05:59:26 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)) 05:59:26 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)) 05:59:26 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)) 05:59:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:59:26 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 05:59:26 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 05:59:26 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 05:59:26 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 05:59:26 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 05:59:26 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 05:59:28 INFO - Downloading blessings-1.6.tar.gz 05:59:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:59:28 INFO - Installing collected packages: blessings 05:59:28 INFO - Running setup.py install for blessings 05:59:28 INFO - Successfully installed blessings 05:59:28 INFO - Cleaning up... 05:59:28 INFO - Return code: 0 05:59:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:59:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:59:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:59:28 INFO - Reading from file tmpfile_stdout 05:59:28 INFO - Current package versions: 05:59:28 INFO - argparse == 1.2.1 05:59:28 INFO - blessings == 1.6 05:59:28 INFO - blobuploader == 1.2.4 05:59:28 INFO - docopt == 0.6.1 05:59:28 INFO - functools32 == 3.2.3-2 05:59:28 INFO - jsonschema == 2.5.1 05:59:28 INFO - manifestparser == 1.1 05:59:28 INFO - mozInstall == 1.12 05:59:28 INFO - mozcrash == 0.17 05:59:28 INFO - mozdebug == 0.1 05:59:28 INFO - mozdevice == 0.48 05:59:28 INFO - mozfile == 1.2 05:59:28 INFO - mozhttpd == 0.7 05:59:28 INFO - mozinfo == 0.9 05:59:28 INFO - mozleak == 0.1 05:59:28 INFO - mozlog == 3.3 05:59:28 INFO - moznetwork == 0.27 05:59:28 INFO - mozprocess == 0.23 05:59:28 INFO - mozprofile == 0.28 05:59:28 INFO - mozrunner == 6.12 05:59:28 INFO - mozscreenshot == 0.1 05:59:28 INFO - mozsystemmonitor == 0.3 05:59:28 INFO - moztest == 0.7 05:59:28 INFO - mozversion == 1.4 05:59:28 INFO - psutil == 3.1.1 05:59:28 INFO - requests == 1.2.3 05:59:28 INFO - wsgiref == 0.1.2 05:59:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:28 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')]} 05:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:28 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')]} 05:59:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:28 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 0x7fc841d67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8370>, '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 0x27bb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bbd80>, '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': '4300f881736b422476174874000001bc-1472042438.804563-876286220', '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 05:59:28 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 05:59:28 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 05:59:28 INFO - Using env: {'DISPLAY': ':0', 05:59:28 INFO - 'HOME': '/home/cltbld', 05:59:28 INFO - 'LANG': 'en_US.UTF-8', 05:59:28 INFO - 'LANGUAGE': 'en_US:en', 05:59:28 INFO - 'LOGNAME': 'cltbld', 05:59:28 INFO - 'MAIL': '/var/mail/cltbld', 05:59:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:28 INFO - 'MOZ_NO_REMOTE': '1', 05:59:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:28 INFO - 'NO_EM_RESTART': '1', 05:59:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:28 INFO - 'PWD': '/builds/slave/test', 05:59:28 INFO - 'SHELL': '/bin/bash', 05:59:28 INFO - 'SHLVL': '1', 05:59:28 INFO - 'TERM': 'linux', 05:59:28 INFO - 'TMOUT': '86400', 05:59:28 INFO - 'USER': 'cltbld', 05:59:28 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1472042438.804563-876286220', 05:59:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:28 INFO - '_': '/tools/buildbot/bin/python'} 05:59:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:29 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)) 05:59:29 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)) 05:59:29 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)) 05:59:29 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)) 05:59:29 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)) 05:59:29 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)) 05:59:29 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)) 05:59:29 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)) 05:59:29 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)) 05:59:29 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)) 05:59:29 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)) 05:59:29 INFO - Cleaning up... 05:59:29 INFO - Return code: 0 05:59:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:59:29 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')]} 05:59:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:29 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')]} 05:59:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:29 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 0x7fc841d67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bd850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b8370>, '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 0x27bb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bbd80>, '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': '4300f881736b422476174874000001bc-1472042438.804563-876286220', '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 05:59:29 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 05:59:29 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 05:59:29 INFO - Using env: {'DISPLAY': ':0', 05:59:29 INFO - 'HOME': '/home/cltbld', 05:59:29 INFO - 'LANG': 'en_US.UTF-8', 05:59:29 INFO - 'LANGUAGE': 'en_US:en', 05:59:29 INFO - 'LOGNAME': 'cltbld', 05:59:29 INFO - 'MAIL': '/var/mail/cltbld', 05:59:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:29 INFO - 'MOZ_NO_REMOTE': '1', 05:59:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:29 INFO - 'NO_EM_RESTART': '1', 05:59:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:29 INFO - 'PWD': '/builds/slave/test', 05:59:29 INFO - 'SHELL': '/bin/bash', 05:59:29 INFO - 'SHLVL': '1', 05:59:29 INFO - 'TERM': 'linux', 05:59:29 INFO - 'TMOUT': '86400', 05:59:29 INFO - 'USER': 'cltbld', 05:59:29 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1472042438.804563-876286220', 05:59:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:29 INFO - '_': '/tools/buildbot/bin/python'} 05:59:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:59:29 INFO - Cleaning up... 05:59:29 INFO - Return code: 0 05:59:29 INFO - Running post-action listener: _resource_record_post_action 05:59:29 INFO - Running post-action listener: _start_resource_monitoring 05:59:29 INFO - Starting resource monitoring. 05:59:29 INFO - [mozharness: 2016-08-24 12:59:29.294263Z] Finished create-virtualenv step (success) 05:59:29 INFO - [mozharness: 2016-08-24 12:59:29.294774Z] Running install step. 05:59:29 INFO - Running pre-action listener: _resource_record_pre_action 05:59:29 INFO - Running main action method: install 05:59:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:59:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:59:29 INFO - Reading from file tmpfile_stdout 05:59:29 INFO - Detecting whether we're running mozinstall >=1.0... 05:59:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:59:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:59:29 INFO - Reading from file tmpfile_stdout 05:59:29 INFO - Output received: 05:59:29 INFO - Usage: mozinstall [options] installer 05:59:29 INFO - Options: 05:59:29 INFO - -h, --help show this help message and exit 05:59:29 INFO - -d DEST, --destination=DEST 05:59:29 INFO - Directory to install application into. [default: 05:59:29 INFO - "/builds/slave/test"] 05:59:29 INFO - --app=APP Application being installed. [default: firefox] 05:59:29 INFO - mkdir: /builds/slave/test/build/application 05:59:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:59:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:59:46 INFO - Reading from file tmpfile_stdout 05:59:46 INFO - Output received: 05:59:46 INFO - /builds/slave/test/build/application/firefox/firefox 05:59:46 INFO - Running post-action listener: _resource_record_post_action 05:59:46 INFO - [mozharness: 2016-08-24 12:59:46.573546Z] Finished install step (success) 05:59:46 INFO - [mozharness: 2016-08-24 12:59:46.573796Z] Running run-tests step. 05:59:46 INFO - Running pre-action listener: _resource_record_pre_action 05:59:46 INFO - Running main action method: run_tests 05:59:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:59:46 INFO - Python 2.7.3 05:59:46 INFO - Return code: 0 05:59:46 INFO - grabbing minidump binary from tooltool 05:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:46 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 0x27b8370>, '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 0x27bb8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bbd80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:59:46 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 05:59:46 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 05:59:46 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 05:59:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:59:46 INFO - Return code: 0 05:59:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:59:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:59:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:59:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:59:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:59:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 05:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 05:59:46 INFO - Using env: {'DISPLAY': ':0', 05:59:46 INFO - 'HOME': '/home/cltbld', 05:59:46 INFO - 'LANG': 'en_US.UTF-8', 05:59:46 INFO - 'LANGUAGE': 'en_US:en', 05:59:46 INFO - 'LOGNAME': 'cltbld', 05:59:46 INFO - 'MAIL': '/var/mail/cltbld', 05:59:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:59:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:59:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:46 INFO - 'MOZ_NO_REMOTE': '1', 05:59:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:59:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:59:46 INFO - 'NO_EM_RESTART': '1', 05:59:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:59:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:46 INFO - 'PWD': '/builds/slave/test', 05:59:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:59:46 INFO - 'SHELL': '/bin/bash', 05:59:46 INFO - 'SHLVL': '1', 05:59:46 INFO - 'TERM': 'linux', 05:59:46 INFO - 'TMOUT': '86400', 05:59:46 INFO - 'USER': 'cltbld', 05:59:46 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1472042438.804563-876286220', 05:59:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:46 INFO - '_': '/tools/buildbot/bin/python'} 05:59:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 05:59:46 INFO - mozversion application_buildid: 20160824030337 05:59:46 INFO - mozversion application_changeset: bd7645928990649c84609d3f531e803c2d41f269 05:59:46 INFO - mozversion application_display_name: Nightly 05:59:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:59:46 INFO - mozversion application_name: Firefox 05:59:46 INFO - mozversion application_remotingname: firefox 05:59:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:59:46 INFO - mozversion application_vendor: Mozilla 05:59:46 INFO - mozversion application_version: 51.0a1 05:59:46 INFO - mozversion platform_buildid: 20160824030337 05:59:46 INFO - mozversion platform_changeset: bd7645928990649c84609d3f531e803c2d41f269 05:59:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:59:46 INFO - mozversion platform_version: 51.0a1 05:59:46 INFO - using testdate: 1472043586 05:59:46 INFO - actual date: 1472043586 05:59:46 INFO - starting webserver on 'localhost:44431' 05:59:46 INFO - SUITE-START | Running 2 tests 05:59:46 INFO - TEST-START | dromaeo_css 05:59:46 INFO - operating with platform_type : linux_ 05:59:46 INFO - Initialising browser for dromaeo_css test... 05:59:46 INFO - TEST-INFO | started process 9898 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgJXkxx/profile http://localhost:44431/getInfo.html) 05:59:51 INFO - PROCESS | 9898 | __metrics Screen width/height:1600/1200 05:59:51 INFO - PROCESS | 9898 | colorDepth:24 05:59:51 INFO - PROCESS | 9898 | Browser inner width/height: 1024/697 05:59:51 INFO - PROCESS | 9898 | __metrics 05:59:51 INFO - PROCESS | 9898 | 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" 05:59:51 INFO - PROCESS | 9898 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:59:52 INFO - PROCESS | 9898 | console.error: PushService: 05:59:52 INFO - PROCESS | 9898 | stateChangeProcessEnqueue: Error transitioning state 05:59:52 INFO - PROCESS | 9898 | UnknownError 05:59:53 INFO - TEST-INFO | 9898: exit 0 05:59:53 INFO - Browser initialized. 05:59:53 INFO - Running cycle 1/1 for dromaeo_css test... 05:59:53 INFO - TEST-INFO | started process 9978 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgJXkxx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:00:05 INFO - PROCESS | 9978 | RSS: Main: 188735488 06:00:05 INFO - PROCESS | 9978 | 06:00:56 INFO - PROCESS | 9978 | 1472043656909 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] 06:03:06 INFO - PROCESS | 9978 | Cycle 1(1): loaded http://localhost:44431/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44431/tests/dromaeo/cssquery-ext.html) 06:03:07 INFO - PROCESS | 9978 | RSS: Main: 295747584 06:03:07 INFO - PROCESS | 9978 | 06:06:10 INFO - PROCESS | 9978 | Cycle 1(1): loaded http://localhost:44431/tests/dromaeo/cssquery-ext.html (next: http://localhost:44431/tests/dromaeo/cssquery-jquery.html) 06:06:10 INFO - PROCESS | 9978 | RSS: Main: 276905984 06:06:10 INFO - PROCESS | 9978 | 06:09:12 INFO - PROCESS | 9978 | Cycle 1(1): loaded http://localhost:44431/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44431/tests/dromaeo/cssquery-mootools.html) 06:09:13 INFO - PROCESS | 9978 | RSS: Main: 216801280 06:09:13 INFO - PROCESS | 9978 | 06:12:15 INFO - PROCESS | 9978 | Cycle 1(1): loaded http://localhost:44431/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44431/tests/dromaeo/cssquery-prototype.html) 06:12:16 INFO - PROCESS | 9978 | RSS: Main: 246693888 06:12:16 INFO - PROCESS | 9978 | 06:15:19 INFO - PROCESS | 9978 | Cycle 1(1): loaded http://localhost:44431/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44431/tests/dromaeo/cssquery-yui.html) 06:15:19 INFO - PROCESS | 9978 | RSS: Main: 217300992 06:15:19 INFO - PROCESS | 9978 | 06:18:23 INFO - PROCESS | 9978 | Cycle 1(1): loaded http://localhost:44431/tests/dromaeo/cssquery-yui.html (next: http://localhost:44431/tests/dromaeo/cssquery-dojo.html) 06:18:23 INFO - PROCESS | 9978 | RSS: Main: 306712576 06:18:23 INFO - PROCESS | 9978 | 06:18:23 INFO - PROCESS | 9978 | __start_tp_report 06:18:23 INFO - PROCESS | 9978 | _x_x_mozilla_page_load 06:18:23 INFO - PROCESS | 9978 | _x_x_mozilla_page_load_details 06:18:23 INFO - PROCESS | 9978 | |i|pagename|runs| 06:18:23 INFO - PROCESS | 9978 | |0;dojo.html;3015.200971742488;3023.4860073787454;3054.114306851013;3067.815931044139;3106.409782141393;5175.58595312375;5295.443978382267;5327.227552004967;5372.838814835552;5456.727163641817;5339.599530035253;5375.301210842585;5387.1111266641055;5389.83830485086;5421.83734487966;4932.926006109911;5011.699298042111;5053.949460505403;5057.645964782467;5062.038212739586;4932.53140951609;4979.950200497985;4991.176455884774;5016.3227964224725;5067.239914012891;4950.3069570260195;5019.096562618727;5117.539421452068;5120.92318615221;5126.410462796789;4995.55040046394;5031.899362012739;5053.418856831475;5061.974690126526;5101.183810590306;4784.976075119637;4829.686070405448;4913.6314776391555;4952.306677065214;4999.925001124986;5328.413874473844;5331.173668081454;5345.33183352081;5413.675179489204;5552.555795536347;5727.221504754417;5776.393478684719;5780.450857168605;5801.187833703285;5816.214811000503;5686.3744988051285;5798.768049278024;5850.385709500484;5886.6468011919;5899.439553242478;5451.890962180813;5478.890422191534;5486.615936884459;5536.667799932017;5547.362053363874;6012.278526576839;6015.969920150459;6022.277326720821;6023.909641355383;6024.548158888101;5482.451754824486;5604.8318550443555;5672.432756724294;5689.203511508392;5761.423857614205;5818.912716309259;5822.82531524055;5823.82528524145;5825;5884.705764711747;5790.160426738099;5802.88394232113;5805.564582656318;5810.186573879576;5812.157237200582;5608.383077861432;5614.831555053355;5674.914876276859;5765.250517432707;5798.1302804579655;4415.977920110379;4442.777861106996;4449.755263460541;4451.043025749479;4469.843555475542;3987.501562304712;4033.3143365627916;4043.7169398142437;4068.7762173080173;4080.775557344315;5497.230387745719;5546.334439867242;5558.583106267046;5560.694161821058;5596.832095037155;5695.174199741097;5699.2590963174525;5755.798547050917;5763.596548241583;5821.38875418075;5554.583406244547;5568.053430916673;5601.467860553241;5610.1584762285165;5627.184058311522;5023.070731914608;5034.723090230073;5038.269450929594;5038.34501514808;5101.923471147936;5863.091220860603;6002.399760024137;6051.062085376773;6071.939280607226;6073.240844894388;5736.225609542658;5790.305163380422;5792.768289268551;5824.592278540462;5884.911726324023;5998.700064996646;6031.638101713911;6047.848803779941;6049.213602231682;6052.848678783065;5556.944430555643;5712.971435142797;5713.600047996601;5714.714264286852;5718;5797.652140871562;5820.330661973758;5855.502282306052;5870.471657550841;5953.28560572734;5747.683877386784;5754;5760.395158508464;5762.625429347079;5763.682997435181;5504.779808807602;5695.117256825199;5706.714664266687;5722.713864306685;5732.885342293047;5569.05326094573;5604.635698679573;5622.072358060874;5627.015272327244;5653.3781284057895;5593.412691667317;5647.1529270609735;5653.152027195953;5656.88686226265;5669.744861481326;5369;5475.945240547703;5483.506484416422;5492.615516913777;5496.31296087989 06:18:23 INFO - PROCESS | 9978 | |1;ext.html;570.2643589769198;605.3976293587881;608.0757248981548;611.2176414189838;615.1019511513191;32357.191070223256;43690.56309436905;44448.3332750009;44461.11077778442;44519;45937.77031114848;46145.07709845801;46341.30488042674;46478.53521464784;46536.76731616346;8038.839223215532;8805.075467075945;8955.238804701576;8968.865467018;8974.910250897472;10443.373397596131;10699.893001070006;10708.1433485321;11020.228583999124;11044.944775276112;10832.079273261743;10916.345019298828;10931.289466184713;10934.179936504754;10936;10709.410982395964;10783.083437907788;10813.134949204084;10817.83773243402;10818.567257309698;10636.62771802991;10663.893361066444;10668.146548276198;10668.679939601825;10677.519511621995;43657.56342436567;44000.77999610014;44048;44100.3384949226;44102.33846492305;3398.3713013092656;3471.3057388522398;3483.4948932404914;3538.663826936437;3543.704354949896;32909;33271.6672833271;33725.109859070544;34004.149896252544;34275.48586771201;4248.936265956013;4291.420658211133;4305.870823875289;4389.5390983946545;4433.911321773547;348675;349288;350390;352429.23785381165;352820.60511528404;56561.71719141378;57155.42844571501;57194.42805571891;57226.141607875914;57233.71383143058;36244.81877590595;37060.44409333862;37112.44331335032;39253.80373098116;39261;15943.601409964844;15945.20273986296;16077.67844643124;16098.517044488684;16113.27490262941;11102.056325212287;11822.704432389259;12060.035197184205;12146.63560093181;12241.571544995883;19007.1446784895;19310.71033934492;19319.613607727766;19394.8060519393;19455.8054419454;7040.577565345993;7299.452541059336;7358.889616655754;7377.188509263977;7391.186969433358;966.3428868369435;991.2615101749099;1034.7826956339102;1121.6522877907876;1161;32676.836615817243;32683;32759.344813103606;32936;32972;1070.817960946641;1075.6880504653718;1080.632999255942;1085.2945585369573;1166.1953252255917;16408.589785255463;16985.320587176375;17103.14484275781;17150.57123571896;17231.3969011084;555428;558237;558519;560169;564793;59806;60156.39843601595;60359.396406036256;60385.69807151024;60484.69757651183;1225.5955534673715;1333.8999575031912;1340.0284793524618;1348.1843484691817;1379.4689044717654;32233.648457457944;33973.49039764455;34063.65936340605;34421.483677744356;34525.30949380948;50929;51637.48362516477;51674.74162629163;51719.482805171465;51816;407268;407743;409848;409934;410654;28392.57411138876;28934.672512661313;29053.273668158465;30509.69490305157;30668;49048.75475622599;49104.508954909994;49489.75255123701;49507.468692096976;49598.25602616036;40908.59091409048;41695.166096678506;42178;42204.57795422006;43458.348124778786;41129.79435102805;41543.169136616496;41553.58446415497;42335;43139.352909707006;41315.586844131176;42455.15089698251;42672.78663606662;42713.1457370857;42817.357739633306;35330;37319.44020839635;37806.20658637683;37956.430653540774;38174;27805.721942780314;28296.292592685346;28348.85825570859;28436;28550.85724571364 06:18:23 INFO - PROCESS | 9978 | |2;jquery.html;376.3414025455453;394.87561418153297;407.57408508109035;408.97955102244885;425.0839441004636;13755.449782008707;19310.517237069063;19331.130099145535;19498.805011949877;19570.804291957076;18676.346327878528;18793.62412751744;19373.515662108453;19605.411837644893;19890.900545497254;5634.295713035871;5702.059160238566;5925;5933.7033148342625;6741.325867413244;6682.496438301392;6699.3300669933105;6709.597424154542;6720.563163394364;6755.8311042224095;6368.267649220329;6490.610563366189;6558.672066396685;6559.34406559345;6562.8687426251445;6368.363163683641;6541.247756507991;6546.770863019771;6579.769708060243;6582.341765823403;5848.093545500455;5852.209951656557;5852.76588936442;5854.297484301868;5854.912176317359;23424.531509369717;23703.644445333335;23719;23757.16849910245;24043.639345409832;2346.691602544647;2353.458704497958;2394.150076722756;2710.5527587948163;2866.870990805419;14234.430622775077;15044.548663540112;15088.24558772068;15166.696666066617;15735.370585176694;1893.6591413545557;1920.7096758117139;1948.2304489726478;2007.8192962633286;2015.2342109998253;80712.78930816043;82234;83428;87813;87962.56018719866;11175.720606984727;11371.886281137082;11398.373089479992;11427.200095993367;11731.296122232694;16153.596160095856;16231.107289098976;16234.75647865283;16279.186040697918;16293.592660183353;7948.483348582324;8146.144654811278;8230.547319897463;8361.790955226046;8425.831483370299;7717.305442510201;7722.884156737653;7775.922240777633;7942.602869856484;7968.282854543003;4779.139754844162;4901.877453063631;4919.385076865392;5088.160453525127;5123.97438012815;1914.2056046740447;1993.6511110555596;2026.0232314671778;2078.9168433262803;2097.5280561873456;958.4680502321139;1011.1405305490274;1021.6730646193296;1036.5387402605918;1042.103790739963;19386.321478748177;19846;20011.799882001284;20090.69863952042;20153.596928061357;928.7817362919709;983.4050399508194;1014.8832884218261;1015.6496008876926;1040.427764729396;10333.638322658671;10648.520816563274;11248.28668480952;11249.381284029458;11356.829647555292;446945;450519;454652;456305;458677;91860.54069729609;92328.53835730779;93900;95159.52420237994;95552;940.4498368454394;965.8213230552229;984.675057231112;985.8570507276548;990.2820455170088;16924.915375423043;17366.82633173652;18070.5482362942;18117.45647630573;18492.167852446404;43002.35496467493;43400.34899476447;43837.78081109574;44486.11027779491;44787.328190077824;63064.36935630769;63286.36713632989;64089.67955160194;64471.677641611495;64520;8694.17405346491;8720.912790872264;8812.206901378906;8952.328575357005;9119.817603647758;12453.315067671003;13595.864041359855;13717.245551494365;13849.86150138526;13870.167789932637;11565.132615054074;11814.232074915102;12014.819777703517;12268.263904165779;12537.185082969578;11148.219624626197;11391;11690.88309116932;11857.976216064848;12120.818187727367;10880.891191088305;11606.24559403636;11607;11777;12198.207116537396;8723.08407617191;8889.64441422336;8965.372423930523;9019.774505637411;9162.267018638626;10144.64493742745;11342.376169310766;11730.472128754056;11807.350595717318;11920.582779602672 06:18:23 INFO - PROCESS | 9978 | |3;mootools.html;1299.9155054921423;1439.1868594244254;1476.889233307502;1568.2472413241644;1761.7093179625363;7440.181580026193;8749.300055995522;9027.72916812495;9028.277737780978;9281.118293762098;9023.909760902388;9098.909010909905;9100.8634870477;9105.27157827374;9121.315901307395;2292.32376448948;2322.628379459287;2323.825713071518;2325.453518423169;2327.1156960355042;2353.576356255873;2382.213869423095;2426.8301218914594;2428.6964129483813;2428.8178386621075;2370.312609343297;2384.8688322142275;2394.8084153267782;2404.6873906392143;2412.9758702413014;2290.2213247495765;2335.6846825678485;2341.344423561397;2343.847649902751;2358.6226203807482;2290.484640955788;2309.872956987365;2310.5609934112463;2316.3514216019544;2318.26974503031;8959.104089591054;9081.682141125095;9103.590338434786;9216.585253663601;9222.584983675684;5279.9208011879855;5411.621186516945;5416.133418652997;5424.647397919122;5434.673919564839;3498.860045598173;3518.0501264658656;3569.39320315547;3574.428091505373;3589.46158076288;1209.9879001209963;1216.355331674214;1233.8951189148934;1251.5056552661647;1267.100358745292;12754.872451275554;12758.681032974157;12831.550895718698;12843.29361885096;12860.228386296945;4495.662825288116;4642.744649044267;4791.161546729309;4805.303231031481;4837.758112094381;10051.095401413908;10059.446730429896;10068.59725610982;10070.194384449373;10077.445740484198;5319.122344813062;5320.388155362183;5322.308099947059;5324.547413469899;5328.0675881720545;4317.935230971538;4804.711717296974;4934.876628084255;4961.652684312064;4977.925331120036;3811.4282857571034;3844.2695887781233;3889.7860617665733;3958.2083583282883;3973.0663294125857;1203.211896207985;1209.1354681402838;1216.3127832774453;1224.4979558381199;1227.699213692651;782.4992005116729;843.9789005274918;899.3030401438937;925.9629614815332;967.1392460709974;5875.471207591252;5981.3719559445635;6118.479929205978;6151.5386346024225;6206.548413376691;453.2933505352351;469.7838994062701;481.9975900120477;486.88071422501713;488.8142505847826;3986.7009974252046;4066.4916885389325;4082.775447350424;4128.91742165155;4208.85269015583;10757.709074910905;11221.775564488664;11359.943200284111;11447.484863181176;11448.484818183202;11166.553337866395;11338.2630129043;11459.885401146048;11468.254563453349;11473.885261147449;8347.290480309242;8432.873506897402;8447.23974842255;8448.57757112142;8457.915420845837;5749.13762935563;5785.247917770663;5828.504577110993;5883.146943693226;5935.821925342247;10512.211584131373;10523.263371563917;10532.789344212919;10554.472276386303;10582.4708764563;10188.083072523508;10600.734981625521;10613.681589552325;11002.944985275022;11005.17461190398;7045.753398631024;7904.525728456311;8109.7161599343735;8135.349172066339;8293.419460637939;8672.392932494664;8872.556372181494;8883.511406872683;8885.733427997171;8886.866696999421;7663.501872378278;7736.419768517271;7926.524408535276;8007.679692812222;8344.413231897888;7747.883781743166;7857.096433910174;7873.881891771743;7874.488158269694;7880.645370958436;7109.82225444368;7173.24680908518;7197.280271972971;7204.027456293543;7212.639368031682;5111.437741848319;5119.74401279942;5125.974805039082;5142.331496905378;5444.183372493999;5110.897782044264;5123.38519377677;5167.819126330561;5200.141976573975;5528.281323428089 06:18:23 INFO - PROCESS | 9978 | |4;prototype.html;332.93007500604887;338.94237979543476;339.1735504309294;341.74027738918414;342.74123037106983;5079.7968081276795;5220.694826293427;5283.867903302415;5294.549963253128;5370.91943620846;5196.662216955895;5244;5250.501202385767;5273.734303767097;5295.920561191576;4171.6859189355255;4173.853915112972;4177.937330940038;4180.205760905425;4189.868735441429;3952.8023598820087;4083.5294646738594;4161.354989976543;4177.979110104445;4179.874603761892;4060.5127384713874;4061.7562946223175;4068.6948478864206;4069.857554985576;4071;4057.5536690963972;4059.8782036538805;4062.1266427717887;4072;4083.9387409188885;3578.212793185521;3585.8386372613295;3591.425371940477;3594.7663401878795;3601.9014200668785;5025.296458495814;5103.642745007851;5106.514881086328;5115.795368185268;5128.615353848476;4672.229082201433;4680.953190468086;4681.578657920803;4706.999525201775;4712.057588482283;5454.209139674765;5462.535684466825;5466.644668096561;5475.67145971243;5481.068218402881;3836.9232615347537;3976.681865450757;3979.721419500607;3989.122393073519;3997.5802540733093;5651.886962260773;5664.490195882349;5679.359098019399;5698.373178950312;5704.77180912763;4069.674426045909;4072.490938632671;4076.5719599442223;4086.40747091676;4091.754494730326;4004.0990777074926;4087.7138600297694;4103.2409004334295;4130.132672138884;4140.772257525865;4660.650451216105;4690.756949408341;4694.953050469451;4702.952970470252;4704.094841589162;5420.349558053059;5508.889822203533;5527.806526771544;5533.03171944908;5537.501624853782;1354.9051566390456;1371.636516323175;1405.8734713875801;1417.9152947994792;1435.4186554445466;941.6045260990401;969.699393188114;970.1850445625709;981.8281800684852;988.7874107067015;5311.867203319871;5312.335958005249;5318.813841515528;5331.813386531453;5338.786448542092;5567.387587365387;5568.220449137124;5572.746132120322;5578.633234857486;5592.832215033555;5123.615728820353;5297.073012222845;5324.1747529133045;5332.866678333073;5350.839474815762;2649.9400239904194;2651.668541432321;2653.5223659741046;2665.1743555664434;2668.8532130732606;4974.228994505857;5005.649604527721;5030.396352437658;5036.823711170092;5053.84838454847;4689.273162659793;4939.802407903644;5000.824971125993;5008.29883816266;5011.448740638527;5018.770401251643;5025.542592648091;5051.419086805065;5058.873528161752;5088.109580823415;4873.952100298453;4916.901661966812;4927.841957140141;4928.802847886044;4933.728644924564;4878.243872199815;5216.9739151304;5578.959467090526;5630.324361076697;5632.324121105494;5908.822735317947;5910.881782364243;5921.703914804329;5936.851578710394;5942.940570594239;4096.6517845982235;4105.363668631367;4112.506499219993;4120.5578047683075;4120.75275483472;5416.729163541886;5444.128939369682;5655.236543066633;5682.971585142048;5709.600327977002;5411.458854114557;5463;5518.172274158908;5551.306086739158;5559.805406810904;5509.586780991362;5528.695921724344;5535.446455354593;5539.670479085071;5539.833804985857;5307.867303317448;5479.671219726829;5482.780688772405;5495.175723641486;5519.96401978918;5572.554195664256;5582.4138465462165;5589.524890384363;5591.776328946959;5598.272224610774;5429.484199001088;5430.348358197041;5436.9456305438025;5438.646487978269;5449 06:18:23 INFO - PROCESS | 9978 | |5;yui.html;702.452614297796;717.9066721326227;791.982302740978;816.9673213071477;843.8607629741093;14902.92548537257;15618.28198898123;15631.140287284195;15713.528594142179;15944.282507287186;13511;13559.864401355984;13604.455821767118;13641.863581364183;13736;658.7793089315085;801.4470015689185;824.1511243419258;826.8470332988386;828.581566309013;674.7166190200128;677.8949262864264;681.2063945503508;794.6026986506747;816.1960468938091;854.3208149521146;862.4135587800293;865.0830120072743;865.5672163918041;865.7013330401002;856.5246288310017;858.1632907914795;858.274757829633;858.8883445152153;859.0035558751817;502.41468688977295;607.0954278125605;636.7834936121741;636.8917284061674;637.4135795068548;13209.603711888658;13441.059125861193;13915;13992.30038498071;14126.85873141276;13981.300934953211;14763.188024658639;14829.25853707321;14883.33025013866;15016.624584385369;12969.293451257043;13202.86797132026;13349.13230640015;13630.93184534071;14030.508932187324;4490.8877278067985;4557.635389168859;4574.519675434065;4597.908041839144;4600.9769951150365;75408.62295688486;76900;77812;77889;77919.61040194763;11182.944085279521;11284.266522676;11522.654320370402;11566.595169169206;11614.825777613343;21288.574228515343;21296.041678124522;21310.467238318855;21313.573728525655;21314.761949995336;8496.40525163245;8597.5701214939;8653.740387788377;8700.738977830675;8957.372983891195;4856.538628830255;4949.90100197994;4958.504149585013;5051.722155281494;5179.015986962465;3419.1623052352334;3452.223249768782;3493.6681015303507;3500.842462089212;3520.190356218084;621.3382747374033;622.7554441360562;640.2349192714754;644.887144749667;649.383086068237;555.6971450559455;592.2567178191342;598.9161517387569;648.8345471904634;653.5294587896707;9476.241900647932;9528.809423811485;9537.14165725088;9573.856392154123;9752;543.9129739241703;620.6865532905854;648.6983552648041;670.503827167902;672.1564436632093;4471.575200355961;4584.7936842842155;4675.378923499243;4789.880252993703;4899.510048995072;121348;124725.75274247142;130207;131736;134545;105358;109122.45438772756;110847;111702;116657;92744;96084.51957740166;97395;97712.02287977171;98850.50574747224;8833.955830220808;9116.361854670375;9179.495127768037;9181.540922953693;9204.631814727603;52600.47399526109;54495.27799580987;54605;54743.967245405656;59660.40339596722;50980.49019509906;57949.71025144847;63181.68409157925;63585.68207158935;65790.34209657842;13553.661158470723;13682.110662806886;13738.244396558286;13901.791473128114;14459;14969.476068337555;15224.619384515476;15245.695086098438;15453.768193476883;15621.297041632926;14139.36372863203;14447.78328325055;14468.27658617086;14614.415423382945;15122.54632361031;13999.930000349934;14066.156030638194;14078.788818167941;14415.639609009859;14574.927125364306;12953.740925181632;13234.470621175431;13356.465741370625;13409.731805364034;14186.716265674835;10733.731656708645;10992.780144397228;11026.393548354918;11347.900638381925;11523.481443335239;10937.507812148313;11436.313821170756;11441.942790285995;11458.369789661649;11666.416679166177 06:18:23 INFO - PROCESS | 9978 | __end_tp_report 06:18:23 INFO - PROCESS | 9978 | __start_cc_report 06:18:23 INFO - PROCESS | 9978 | _x_x_mozilla_cycle_collect,1037 06:18:23 INFO - PROCESS | 9978 | __end_cc_report 06:18:23 INFO - PROCESS | 9978 | __startTimestamp1472044703419__endTimestamp 06:18:23 INFO - PROCESS | 9978 | 06:18:23 INFO - PROCESS | 9978 | ------- Summary: start ------- 06:18:23 INFO - PROCESS | 9978 | Number of tests: 6 06:18:23 INFO - PROCESS | 9978 | 06:18:23 INFO - PROCESS | 9978 | [#0] dojo.html Cycles:165 Average:5401.94 Median:5601.47 stddev:610.26 (10.9%) stddev-sans-first:582.71 06:18:23 INFO - PROCESS | 9978 | Values: 3015.2 3023.5 3054.1 3067.8 3106.4 5175.6 5295.4 5327.2 5372.8 5456.7 5339.6 5375.3 5387.1 5389.8 5421.8 4932.9 5011.7 5053.9 5057.6 5062.0 4932.5 4980.0 4991.2 5016.3 5067.2 4950.3 5019.1 5117.5 5120.9 5126.4 4995.6 5031.9 5053.4 5062.0 5101.2 4785.0 4829.7 4913.6 4952.3 4999.9 5328.4 5331.2 5345.3 5413.7 5552.6 5727.2 5776.4 5780.5 5801.2 5816.2 5686.4 5798.8 5850.4 5886.6 5899.4 5451.9 5478.9 5486.6 5536.7 5547.4 6012.3 6016.0 6022.3 6023.9 6024.5 5482.5 5604.8 5672.4 5689.2 5761.4 5818.9 5822.8 5823.8 5825.0 5884.7 5790.2 5802.9 5805.6 5810.2 5812.2 5608.4 5614.8 5674.9 5765.3 5798.1 4416.0 4442.8 4449.8 4451.0 4469.8 3987.5 4033.3 4043.7 4068.8 4080.8 5497.2 5546.3 5558.6 5560.7 5596.8 5695.2 5699.3 5755.8 5763.6 5821.4 5554.6 5568.1 5601.5 5610.2 5627.2 5023.1 5034.7 5038.3 5038.3 5101.9 5863.1 6002.4 6051.1 6071.9 6073.2 5736.2 5790.3 5792.8 5824.6 5884.9 5998.7 6031.6 6047.8 6049.2 6052.8 5556.9 5713.0 5713.6 5714.7 5718.0 5797.7 5820.3 5855.5 5870.5 5953.3 5747.7 5754.0 5760.4 5762.6 5763.7 5504.8 5695.1 5706.7 5722.7 5732.9 5569.1 5604.6 5622.1 5627.0 5653.4 5593.4 5647.2 5653.2 5656.9 5669.7 5369.0 5475.9 5483.5 5492.6 5496.3 06:18:23 INFO - PROCESS | 9978 | 06:18:23 INFO - PROCESS | 9978 | [#1] ext.html Cycles:180 Average:60125.12 Median:36652.63 stddev:118874.60 (324.3%) stddev-sans-first:119123.98 06:18:23 INFO - PROCESS | 9978 | Values: 570.3 605.4 608.1 611.2 615.1 32357.2 43690.6 44448.3 44461.1 44519.0 45937.8 46145.1 46341.3 46478.5 46536.8 8038.8 8805.1 8955.2 8968.9 8974.9 10443.4 10699.9 10708.1 11020.2 11044.9 10832.1 10916.3 10931.3 10934.2 10936.0 10709.4 10783.1 10813.1 10817.8 10818.6 10636.6 10663.9 10668.1 10668.7 10677.5 43657.6 44000.8 44048.0 44100.3 44102.3 3398.4 3471.3 3483.5 3538.7 3543.7 32909.0 33271.7 33725.1 34004.1 34275.5 4248.9 4291.4 4305.9 4389.5 4433.9 348675.0 349288.0 350390.0 352429.2 352820.6 56561.7 57155.4 57194.4 57226.1 57233.7 36244.8 37060.4 37112.4 39253.8 39261.0 15943.6 15945.2 16077.7 16098.5 16113.3 11102.1 11822.7 12060.0 12146.6 12241.6 19007.1 19310.7 19319.6 19394.8 19455.8 7040.6 7299.5 7358.9 7377.2 7391.2 966.3 991.3 1034.8 1121.7 1161.0 32676.8 32683.0 32759.3 32936.0 32972.0 1070.8 1075.7 1080.6 1085.3 1166.2 16408.6 16985.3 17103.1 17150.6 17231.4 555428.0 558237.0 558519.0 560169.0 564793.0 59806.0 60156.4 60359.4 60385.7 60484.7 1225.6 1333.9 1340.0 1348.2 1379.5 32233.6 33973.5 34063.7 34421.5 34525.3 50929.0 51637.5 51674.7 51719.5 51816.0 407268.0 407743.0 409848.0 409934.0 410654.0 28392.6 28934.7 29053.3 30509.7 30668.0 49048.8 49104.5 49489.8 49507.5 49598.3 40908.6 41695.2 42178.0 42204.6 43458.3 41129.8 41543.2 41553.6 42335.0 43139.4 41315.6 42455.2 42672.8 42713.1 42817.4 35330.0 37319.4 37806.2 37956.4 38174.0 27805.7 28296.3 28348.9 28436.0 28550.9 06:18:23 INFO - PROCESS | 9978 | 06:18:23 INFO - PROCESS | 9978 | [#2] jquery.html Cycles:180 Average:28682.00 Median:2788.71 stddev:75101.13 (2693.0%) stddev-sans-first:75281.74 06:18:23 INFO - PROCESS | 9978 | Values: 376.3 394.9 407.6 409.0 425.1 13755.4 19310.5 19331.1 19498.8 19570.8 18676.3 18793.6 19373.5 19605.4 19890.9 5634.3 5702.1 5925.0 5933.7 6741.3 6682.5 6699.3 6709.6 6720.6 6755.8 6368.3 6490.6 6558.7 6559.3 6562.9 6368.4 6541.2 6546.8 6579.8 6582.3 5848.1 5852.2 5852.8 5854.3 5854.9 23424.5 23703.6 23719.0 23757.2 24043.6 2346.7 2353.5 2394.2 2710.6 2866.9 14234.4 15044.5 15088.2 15166.7 15735.4 1893.7 1920.7 1948.2 2007.8 2015.2 80712.8 82234.0 83428.0 87813.0 87962.6 11175.7 11371.9 11398.4 11427.2 11731.3 16153.6 16231.1 16234.8 16279.2 16293.6 7948.5 8146.1 8230.5 8361.8 8425.8 7717.3 7722.9 7775.9 7942.6 7968.3 4779.1 4901.9 4919.4 5088.2 5124.0 1914.2 1993.7 2026.0 2078.9 2097.5 958.5 1011.1 1021.7 1036.5 1042.1 19386.3 19846.0 20011.8 20090.7 20153.6 928.8 983.4 1014.9 1015.6 1040.4 10333.6 10648.5 11248.3 11249.4 11356.8 446945.0 450519.0 454652.0 456305.0 458677.0 91860.5 92328.5 93900.0 95159.5 95552.0 940.4 965.8 984.7 985.9 990.3 16924.9 17366.8 18070.5 18117.5 18492.2 43002.4 43400.3 43837.8 44486.1 44787.3 63064.4 63286.4 64089.7 64471.7 64520.0 8694.2 8720.9 8812.2 8952.3 9119.8 12453.3 13595.9 13717.2 13849.9 13870.2 11565.1 11814.2 12014.8 12268.3 12537.2 11148.2 11391.0 11690.9 11858.0 12120.8 10880.9 11606.2 11607.0 11777.0 12198.2 8723.1 8889.6 8965.4 9019.8 9162.3 10144.6 11342.4 11730.5 11807.4 11920.6 06:18:23 INFO - PROCESS | 9978 | 06:18:23 INFO - PROCESS | 9978 | [#3] mootools.html Cycles:180 Average:5914.82 Median:4797.94 stddev:3440.18 (71.7%) stddev-sans-first:3432.35 06:18:23 INFO - PROCESS | 9978 | Values: 1299.9 1439.2 1476.9 1568.2 1761.7 7440.2 8749.3 9027.7 9028.3 9281.1 9023.9 9098.9 9100.9 9105.3 9121.3 2292.3 2322.6 2323.8 2325.5 2327.1 2353.6 2382.2 2426.8 2428.7 2428.8 2370.3 2384.9 2394.8 2404.7 2413.0 2290.2 2335.7 2341.3 2343.8 2358.6 2290.5 2309.9 2310.6 2316.4 2318.3 8959.1 9081.7 9103.6 9216.6 9222.6 5279.9 5411.6 5416.1 5424.6 5434.7 3498.9 3518.1 3569.4 3574.4 3589.5 1210.0 1216.4 1233.9 1251.5 1267.1 12754.9 12758.7 12831.6 12843.3 12860.2 4495.7 4642.7 4791.2 4805.3 4837.8 10051.1 10059.4 10068.6 10070.2 10077.4 5319.1 5320.4 5322.3 5324.5 5328.1 4317.9 4804.7 4934.9 4961.7 4977.9 3811.4 3844.3 3889.8 3958.2 3973.1 1203.2 1209.1 1216.3 1224.5 1227.7 782.5 844.0 899.3 926.0 967.1 5875.5 5981.4 6118.5 6151.5 6206.5 453.3 469.8 482.0 486.9 488.8 3986.7 4066.5 4082.8 4128.9 4208.9 10757.7 11221.8 11359.9 11447.5 11448.5 11166.6 11338.3 11459.9 11468.3 11473.9 8347.3 8432.9 8447.2 8448.6 8457.9 5749.1 5785.2 5828.5 5883.1 5935.8 10512.2 10523.3 10532.8 10554.5 10582.5 10188.1 10600.7 10613.7 11002.9 11005.2 7045.8 7904.5 8109.7 8135.3 8293.4 8672.4 8872.6 8883.5 8885.7 8886.9 7663.5 7736.4 7926.5 8007.7 8344.4 7747.9 7857.1 7873.9 7874.5 7880.6 7109.8 7173.2 7197.3 7204.0 7212.6 5111.4 5119.7 5126.0 5142.3 5444.2 5110.9 5123.4 5167.8 5200.1 5528.3 06:18:23 INFO - PROCESS | 9978 | 06:18:23 INFO - PROCESS | 9978 | [#4] prototype.html Cycles:180 Average:4548.77 Median:5069.34 stddev:1320.48 (26.0%) stddev-sans-first:1285.72 06:18:23 INFO - PROCESS | 9978 | Values: 332.9 338.9 339.2 341.7 342.7 5079.8 5220.7 5283.9 5294.5 5370.9 5196.7 5244.0 5250.5 5273.7 5295.9 4171.7 4173.9 4177.9 4180.2 4189.9 3952.8 4083.5 4161.4 4178.0 4179.9 4060.5 4061.8 4068.7 4069.9 4071.0 4057.6 4059.9 4062.1 4072.0 4083.9 3578.2 3585.8 3591.4 3594.8 3601.9 5025.3 5103.6 5106.5 5115.8 5128.6 4672.2 4681.0 4681.6 4707.0 4712.1 5454.2 5462.5 5466.6 5475.7 5481.1 3836.9 3976.7 3979.7 3989.1 3997.6 5651.9 5664.5 5679.4 5698.4 5704.8 4069.7 4072.5 4076.6 4086.4 4091.8 4004.1 4087.7 4103.2 4130.1 4140.8 4660.7 4690.8 4695.0 4703.0 4704.1 5420.3 5508.9 5527.8 5533.0 5537.5 1354.9 1371.6 1405.9 1417.9 1435.4 941.6 969.7 970.2 981.8 988.8 5311.9 5312.3 5318.8 5331.8 5338.8 5567.4 5568.2 5572.7 5578.6 5592.8 5123.6 5297.1 5324.2 5332.9 5350.8 2649.9 2651.7 2653.5 2665.2 2668.9 4974.2 5005.6 5030.4 5036.8 5053.8 4689.3 4939.8 5000.8 5008.3 5011.4 5018.8 5025.5 5051.4 5058.9 5088.1 4874.0 4916.9 4927.8 4928.8 4933.7 4878.2 5217.0 5579.0 5630.3 5632.3 5908.8 5910.9 5921.7 5936.9 5942.9 4096.7 4105.4 4112.5 4120.6 4120.8 5416.7 5444.1 5655.2 5683.0 5709.6 5411.5 5463.0 5518.2 5551.3 5559.8 5509.6 5528.7 5535.4 5539.7 5539.8 5307.9 5479.7 5482.8 5495.2 5520.0 5572.6 5582.4 5589.5 5591.8 5598.3 5429.5 5430.3 5436.9 5438.6 5449.0 06:18:23 INFO - PROCESS | 9978 | 06:18:23 INFO - PROCESS | 9978 | [#5] yui.html Cycles:180 Average:21604.56 Median:4599.44 stddev:32245.76 (701.1%) stddev-sans-first:32298.02 06:18:23 INFO - PROCESS | 9978 | Values: 702.5 717.9 792.0 817.0 843.9 14902.9 15618.3 15631.1 15713.5 15944.3 13511.0 13559.9 13604.5 13641.9 13736.0 658.8 801.4 824.2 826.8 828.6 674.7 677.9 681.2 794.6 816.2 854.3 862.4 865.1 865.6 865.7 856.5 858.2 858.3 858.9 859.0 502.4 607.1 636.8 636.9 637.4 13209.6 13441.1 13915.0 13992.3 14126.9 13981.3 14763.2 14829.3 14883.3 15016.6 12969.3 13202.9 13349.1 13630.9 14030.5 4490.9 4557.6 4574.5 4597.9 4601.0 75408.6 76900.0 77812.0 77889.0 77919.6 11182.9 11284.3 11522.7 11566.6 11614.8 21288.6 21296.0 21310.5 21313.6 21314.8 8496.4 8597.6 8653.7 8700.7 8957.4 4856.5 4949.9 4958.5 5051.7 5179.0 3419.2 3452.2 3493.7 3500.8 3520.2 621.3 622.8 640.2 644.9 649.4 555.7 592.3 598.9 648.8 653.5 9476.2 9528.8 9537.1 9573.9 9752.0 543.9 620.7 648.7 670.5 672.2 4471.6 4584.8 4675.4 4789.9 4899.5 121348.0 124725.8 130207.0 131736.0 134545.0 105358.0 109122.5 110847.0 111702.0 116657.0 92744.0 96084.5 97395.0 97712.0 98850.5 8834.0 9116.4 9179.5 9181.5 9204.6 52600.5 54495.3 54605.0 54744.0 59660.4 50980.5 57949.7 63181.7 63585.7 65790.3 13553.7 13682.1 13738.2 13901.8 14459.0 14969.5 15224.6 15245.7 15453.8 15621.3 14139.4 14447.8 14468.3 14614.4 15122.5 13999.9 14066.2 14078.8 14415.6 14574.9 12953.7 13234.5 13356.5 13409.7 14186.7 10733.7 10992.8 11026.4 11347.9 11523.5 10937.5 11436.3 11441.9 11458.4 11666.4 06:18:23 INFO - PROCESS | 9978 | -------- Summary: end -------- 06:18:23 INFO - PROCESS | 9978 | 06:18:24 INFO - TEST-INFO | 9978: exit 0 06:18:24 INFO - TEST-OK | dromaeo_css | took 1117540ms 06:18:24 INFO - TEST-START | kraken 06:18:24 INFO - operating with platform_type : linux_ 06:18:24 INFO - Initialising browser for kraken test... 06:18:24 INFO - TEST-INFO | started process 10284 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmxbXge/profile http://localhost:44431/getInfo.html) 06:18:28 INFO - PROCESS | 10284 | __metrics Screen width/height:1600/1200 06:18:28 INFO - PROCESS | 10284 | colorDepth:24 06:18:28 INFO - PROCESS | 10284 | Browser inner width/height: 1024/697 06:18:28 INFO - PROCESS | 10284 | __metrics 06:18:29 INFO - PROCESS | 10284 | 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" 06:18:29 INFO - PROCESS | 10284 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:18:29 INFO - PROCESS | 10284 | console.error: PushService: 06:18:29 INFO - PROCESS | 10284 | stateChangeProcessEnqueue: Error transitioning state 06:18:29 INFO - PROCESS | 10284 | UnknownError 06:18:30 INFO - TEST-INFO | 10284: exit 0 06:18:30 INFO - Browser initialized. 06:18:30 INFO - Running cycle 1/1 for kraken test... 06:18:30 INFO - TEST-INFO | started process 10364 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmxbXge/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:18:42 INFO - PROCESS | 10364 | RSS: Main: 181706752 06:18:42 INFO - PROCESS | 10364 | 06:18:45 INFO - PROCESS | 10364 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44431/tests/kraken/driver.html?testName=audio-beat-detection) 06:18:46 INFO - PROCESS | 10364 | RSS: Main: 244187136 06:18:46 INFO - PROCESS | 10364 | 06:18:48 INFO - PROCESS | 10364 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44431/tests/kraken/driver.html?testName=audio-dft) 06:18:49 INFO - PROCESS | 10364 | RSS: Main: 225882112 06:18:49 INFO - PROCESS | 10364 | 06:18:52 INFO - PROCESS | 10364 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44431/tests/kraken/driver.html?testName=audio-fft) 06:18:53 INFO - PROCESS | 10364 | RSS: Main: 303599616 06:18:53 INFO - PROCESS | 10364 | 06:18:55 INFO - PROCESS | 10364 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44431/tests/kraken/driver.html?testName=audio-oscillator) 06:18:55 INFO - PROCESS | 10364 | RSS: Main: 212639744 06:18:55 INFO - PROCESS | 10364 | 06:18:58 INFO - PROCESS | 10364 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44431/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:18:58 INFO - PROCESS | 10364 | RSS: Main: 215306240 06:18:58 INFO - PROCESS | 10364 | 06:19:01 INFO - PROCESS | 10364 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44431/tests/kraken/driver.html?testName=imaging-darkroom) 06:19:02 INFO - PROCESS | 10364 | RSS: Main: 253472768 06:19:02 INFO - PROCESS | 10364 | 06:19:05 INFO - PROCESS | 10364 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44431/tests/kraken/driver.html?testName=imaging-desaturate) 06:19:06 INFO - PROCESS | 10364 | RSS: Main: 252551168 06:19:06 INFO - PROCESS | 10364 | 06:19:09 INFO - PROCESS | 10364 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44431/tests/kraken/driver.html?testName=json-parse-financial) 06:19:09 INFO - PROCESS | 10364 | RSS: Main: 252317696 06:19:09 INFO - PROCESS | 10364 | 06:19:11 INFO - PROCESS | 10364 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44431/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:19:12 INFO - PROCESS | 10364 | RSS: Main: 214827008 06:19:12 INFO - PROCESS | 10364 | 06:19:13 INFO - PROCESS | 10364 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:19:14 INFO - PROCESS | 10364 | RSS: Main: 221614080 06:19:14 INFO - PROCESS | 10364 | 06:19:16 INFO - PROCESS | 10364 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:19:17 INFO - PROCESS | 10364 | RSS: Main: 222875648 06:19:17 INFO - PROCESS | 10364 | 06:19:19 INFO - PROCESS | 10364 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:19:20 INFO - PROCESS | 10364 | RSS: Main: 219676672 06:19:20 INFO - PROCESS | 10364 | 06:19:23 INFO - PROCESS | 10364 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:19:23 INFO - PROCESS | 10364 | RSS: Main: 221749248 06:19:23 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | Cycle 1(1): loaded http://localhost:44431/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44431/tests/kraken/driver.html?testName=ai-astar) 06:19:25 INFO - PROCESS | 10364 | RSS: Main: 273592320 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | __start_tp_report 06:19:25 INFO - PROCESS | 10364 | _x_x_mozilla_page_load 06:19:25 INFO - PROCESS | 10364 | _x_x_mozilla_page_load_details 06:19:25 INFO - PROCESS | 10364 | |i|pagename|runs| 06:19:25 INFO - PROCESS | 10364 | |0;ai-astar;116;112;128;111;116;109;111;112;111;113 06:19:25 INFO - PROCESS | 10364 | |1;audio-beat-detection;150;116;143;179;121;153;152;123;189;120 06:19:25 INFO - PROCESS | 10364 | |2;audio-dft;161;161;160;160;162;162;162;162;160;161 06:19:25 INFO - PROCESS | 10364 | |3;audio-fft;76;75;75;168;77;77;74;76;163;76 06:19:25 INFO - PROCESS | 10364 | |4;audio-oscillator;85;85;160;86;85;85;144;85;86;144 06:19:25 INFO - PROCESS | 10364 | |5;imaging-gaussian-blur;116;117;116;117;116;116;116;117;117;117 06:19:25 INFO - PROCESS | 10364 | |6;imaging-darkroom;146;146;146;146;146;145;146;146;146;146 06:19:25 INFO - PROCESS | 10364 | |7;imaging-desaturate;95;96;95;96;96;96;96;95;95;96 06:19:25 INFO - PROCESS | 10364 | |8;json-parse-financial;50;50;52;52;52;54;59;52;55;58 06:19:25 INFO - PROCESS | 10364 | |9;json-stringify-tinderbox;62;63;62;61;62;63;63;65;65;64 06:19:25 INFO - PROCESS | 10364 | |10;stanford-crypto-aes;88;81;78;82;87;83;78;84;79;79 06:19:25 INFO - PROCESS | 10364 | |11;stanford-crypto-ccm;127;122;121;131;125;123;124;125;153;129 06:19:25 INFO - PROCESS | 10364 | |12;stanford-crypto-pbkdf2;165;167;162;164;165;161;162;161;161;161 06:19:25 INFO - PROCESS | 10364 | |13;stanford-crypto-sha256-iterative;67;68;68;65;67;68;68;64;66;67 06:19:25 INFO - PROCESS | 10364 | __end_tp_report 06:19:25 INFO - PROCESS | 10364 | __start_cc_report 06:19:25 INFO - PROCESS | 10364 | _x_x_mozilla_cycle_collect,1721 06:19:25 INFO - PROCESS | 10364 | __end_cc_report 06:19:25 INFO - PROCESS | 10364 | __startTimestamp1472044765899__endTimestamp 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | ------- Summary: start ------- 06:19:25 INFO - PROCESS | 10364 | Number of tests: 14 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | [#0] ai-astar Cycles:10 Average:113.90 Median:112.50 stddev:5.43 (4.8%) stddev-sans-first:5.70 06:19:25 INFO - PROCESS | 10364 | Values: 116.0 112.0 128.0 111.0 116.0 109.0 111.0 112.0 111.0 113.0 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | [#1] audio-beat-detection Cycles:10 Average:144.60 Median:151.00 stddev:25.29 (16.8%) stddev-sans-first:26.75 06:19:25 INFO - PROCESS | 10364 | Values: 150.0 116.0 143.0 179.0 121.0 153.0 152.0 123.0 189.0 120.0 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:0.88 (0.5%) stddev-sans-first:0.93 06:19:25 INFO - PROCESS | 10364 | Values: 161.0 161.0 160.0 160.0 162.0 162.0 162.0 162.0 160.0 161.0 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | [#3] audio-fft Cycles:10 Average:93.70 Median:76.00 stddev:37.87 (49.8%) stddev-sans-first:39.62 06:19:25 INFO - PROCESS | 10364 | Values: 76.0 75.0 75.0 168.0 77.0 77.0 74.0 76.0 163.0 76.0 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | [#4] audio-oscillator Cycles:10 Average:104.50 Median:85.00 stddev:31.25 (36.8%) stddev-sans-first:32.33 06:19:25 INFO - PROCESS | 10364 | Values: 85.0 85.0 160.0 86.0 85.0 85.0 144.0 85.0 86.0 144.0 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 06:19:25 INFO - PROCESS | 10364 | Values: 116.0 117.0 116.0 117.0 116.0 116.0 116.0 117.0 117.0 117.0 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 06:19:25 INFO - PROCESS | 10364 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 06:19:25 INFO - PROCESS | 10364 | Values: 95.0 96.0 95.0 96.0 96.0 96.0 96.0 95.0 95.0 96.0 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.00 stddev:3.10 (5.8%) stddev-sans-first:3.03 06:19:25 INFO - PROCESS | 10364 | Values: 50.0 50.0 52.0 52.0 52.0 54.0 59.0 52.0 55.0 58.0 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | [#9] json-stringify-tinderbox Cycles:10 Average:63.00 Median:63.00 stddev:1.33 (2.1%) stddev-sans-first:1.36 06:19:25 INFO - PROCESS | 10364 | Values: 62.0 63.0 62.0 61.0 62.0 63.0 63.0 65.0 65.0 64.0 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:82.50 stddev:3.60 (4.4%) stddev-sans-first:3.07 06:19:25 INFO - PROCESS | 10364 | Values: 88.0 81.0 78.0 82.0 87.0 83.0 78.0 84.0 79.0 79.0 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:126.00 stddev:9.31 (7.4%) stddev-sans-first:9.87 06:19:25 INFO - PROCESS | 10364 | Values: 127.0 122.0 121.0 131.0 125.0 123.0 124.0 125.0 153.0 129.0 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.90 Median:163.00 stddev:2.18 (1.3%) stddev-sans-first:2.18 06:19:25 INFO - PROCESS | 10364 | Values: 165.0 167.0 162.0 164.0 165.0 161.0 162.0 161.0 161.0 161.0 06:19:25 INFO - PROCESS | 10364 | 06:19:25 INFO - PROCESS | 10364 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.50 stddev:1.40 (2.1%) stddev-sans-first:1.48 06:19:25 INFO - PROCESS | 10364 | Values: 67.0 68.0 68.0 65.0 67.0 68.0 68.0 64.0 66.0 67.0 06:19:25 INFO - PROCESS | 10364 | -------- Summary: end -------- 06:19:25 INFO - PROCESS | 10364 | 06:19:27 INFO - TEST-INFO | 10364: exit 0 06:19:27 INFO - TEST-OK | kraken | took 62722ms 06:19:27 INFO - SUITE-END | took 1180s 06:19:27 INFO - Completed test suite (00:19:40) 06:19:27 INFO - javascript benchmark 06:19:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3015.200971742488, 3023.4860073787454, 3054.114306851013, 3067.815931044139, 3106.409782141393, 5175.58595312375, 5295.443978382267, 5327.227552004967, 5372.838814835552, 5456.727163641817, 5339.599530035253, 5375.301210842585, 5387.1111266641055, 5389.83830485086, 5421.83734487966, 4932.926006109911, 5011.699298042111, 5053.949460505403, 5057.645964782467, 5062.038212739586, 4932.53140951609, 4979.950200497985, 4991.176455884774, 5016.3227964224725, 5067.239914012891, 4950.3069570260195, 5019.096562618727, 5117.539421452068, 5120.92318615221, 5126.410462796789, 4995.55040046394, 5031.899362012739, 5053.418856831475, 5061.974690126526, 5101.183810590306, 4784.976075119637, 4829.686070405448, 4913.6314776391555, 4952.306677065214, 4999.925001124986, 5328.413874473844, 5331.173668081454, 5345.33183352081, 5413.675179489204, 5552.555795536347, 5727.221504754417, 5776.393478684719, 5780.450857168605, 5801.187833703285, 5816.214811000503, 5686.3744988051285, 5798.768049278024, 5850.385709500484, 5886.6468011919, 5899.439553242478, 5451.890962180813, 5478.890422191534, 5486.615936884459, 5536.667799932017, 5547.362053363874, 6012.278526576839, 6015.969920150459, 6022.277326720821, 6023.909641355383, 6024.548158888101, 5482.451754824486, 5604.8318550443555, 5672.432756724294, 5689.203511508392, 5761.423857614205, 5818.912716309259, 5822.82531524055, 5823.82528524145, 5825.0, 5884.705764711747, 5790.160426738099, 5802.88394232113, 5805.564582656318, 5810.186573879576, 5812.157237200582, 5608.383077861432, 5614.831555053355, 5674.914876276859, 5765.250517432707, 5798.1302804579655, 4415.977920110379, 4442.777861106996, 4449.755263460541, 4451.043025749479, 4469.843555475542, 3987.501562304712, 4033.3143365627916, 4043.7169398142437, 4068.7762173080173, 4080.775557344315, 5497.230387745719, 5546.334439867242, 5558.583106267046, 5560.694161821058, 5596.832095037155, 5695.174199741097, 5699.2590963174525, 5755.798547050917, 5763.596548241583, 5821.38875418075, 5554.583406244547, 5568.053430916673, 5601.467860553241, 5610.1584762285165, 5627.184058311522, 5023.070731914608, 5034.723090230073, 5038.269450929594, 5038.34501514808, 5101.923471147936, 5863.091220860603, 6002.399760024137, 6051.062085376773, 6071.939280607226, 6073.240844894388, 5736.225609542658, 5790.305163380422, 5792.768289268551, 5824.592278540462, 5884.911726324023, 5998.700064996646, 6031.638101713911, 6047.848803779941, 6049.213602231682, 6052.848678783065, 5556.944430555643, 5712.971435142797, 5713.600047996601, 5714.714264286852, 5718.0, 5797.652140871562, 5820.330661973758, 5855.502282306052, 5870.471657550841, 5953.28560572734, 5747.683877386784, 5754.0, 5760.395158508464, 5762.625429347079, 5763.682997435181, 5504.779808807602, 5695.117256825199, 5706.714664266687, 5722.713864306685, 5732.885342293047, 5569.05326094573, 5604.635698679573, 5622.072358060874, 5627.015272327244, 5653.3781284057895, 5593.412691667317, 5647.1529270609735, 5653.152027195953, 5656.88686226265, 5669.744861481326, 5369.0, 5475.945240547703, 5483.506484416422, 5492.615516913777, 5496.31296087989], "value": 5360.233256538564, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.2643589769198, 605.3976293587881, 608.0757248981548, 611.2176414189838, 615.1019511513191, 32357.191070223256, 43690.56309436905, 44448.3332750009, 44461.11077778442, 44519.0, 45937.77031114848, 46145.07709845801, 46341.30488042674, 46478.53521464784, 46536.76731616346, 8038.839223215532, 8805.075467075945, 8955.238804701576, 8968.865467018, 8974.910250897472, 10443.373397596131, 10699.893001070006, 10708.1433485321, 11020.228583999124, 11044.944775276112, 10832.079273261743, 10916.345019298828, 10931.289466184713, 10934.179936504754, 10936.0, 10709.410982395964, 10783.083437907788, 10813.134949204084, 10817.83773243402, 10818.567257309698, 10636.62771802991, 10663.893361066444, 10668.146548276198, 10668.679939601825, 10677.519511621995, 43657.56342436567, 44000.77999610014, 44048.0, 44100.3384949226, 44102.33846492305, 3398.3713013092656, 3471.3057388522398, 3483.4948932404914, 3538.663826936437, 3543.704354949896, 32909.0, 33271.6672833271, 33725.109859070544, 34004.149896252544, 34275.48586771201, 4248.936265956013, 4291.420658211133, 4305.870823875289, 4389.5390983946545, 4433.911321773547, 348675.0, 349288.0, 350390.0, 352429.23785381165, 352820.60511528404, 56561.71719141378, 57155.42844571501, 57194.42805571891, 57226.141607875914, 57233.71383143058, 36244.81877590595, 37060.44409333862, 37112.44331335032, 39253.80373098116, 39261.0, 15943.601409964844, 15945.20273986296, 16077.67844643124, 16098.517044488684, 16113.27490262941, 11102.056325212287, 11822.704432389259, 12060.035197184205, 12146.63560093181, 12241.571544995883, 19007.1446784895, 19310.71033934492, 19319.613607727766, 19394.8060519393, 19455.8054419454, 7040.577565345993, 7299.452541059336, 7358.889616655754, 7377.188509263977, 7391.186969433358, 966.3428868369435, 991.2615101749099, 1034.7826956339102, 1121.6522877907876, 1161.0, 32676.836615817243, 32683.0, 32759.344813103606, 32936.0, 32972.0, 1070.817960946641, 1075.6880504653718, 1080.632999255942, 1085.2945585369573, 1166.1953252255917, 16408.589785255463, 16985.320587176375, 17103.14484275781, 17150.57123571896, 17231.3969011084, 555428.0, 558237.0, 558519.0, 560169.0, 564793.0, 59806.0, 60156.39843601595, 60359.396406036256, 60385.69807151024, 60484.69757651183, 1225.5955534673715, 1333.8999575031912, 1340.0284793524618, 1348.1843484691817, 1379.4689044717654, 32233.648457457944, 33973.49039764455, 34063.65936340605, 34421.483677744356, 34525.30949380948, 50929.0, 51637.48362516477, 51674.74162629163, 51719.482805171465, 51816.0, 407268.0, 407743.0, 409848.0, 409934.0, 410654.0, 28392.57411138876, 28934.672512661313, 29053.273668158465, 30509.69490305157, 30668.0, 49048.75475622599, 49104.508954909994, 49489.75255123701, 49507.468692096976, 49598.25602616036, 40908.59091409048, 41695.166096678506, 42178.0, 42204.57795422006, 43458.348124778786, 41129.79435102805, 41543.169136616496, 41553.58446415497, 42335.0, 43139.352909707006, 41315.586844131176, 42455.15089698251, 42672.78663606662, 42713.1457370857, 42817.357739633306, 35330.0, 37319.44020839635, 37806.20658637683, 37956.430653540774, 38174.0, 27805.721942780314, 28296.292592685346, 28348.85825570859, 28436.0, 28550.85724571364], "value": 20433.01041468883, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.3414025455453, 394.87561418153297, 407.57408508109035, 408.97955102244885, 425.0839441004636, 13755.449782008707, 19310.517237069063, 19331.130099145535, 19498.805011949877, 19570.804291957076, 18676.346327878528, 18793.62412751744, 19373.515662108453, 19605.411837644893, 19890.900545497254, 5634.295713035871, 5702.059160238566, 5925.0, 5933.7033148342625, 6741.325867413244, 6682.496438301392, 6699.3300669933105, 6709.597424154542, 6720.563163394364, 6755.8311042224095, 6368.267649220329, 6490.610563366189, 6558.672066396685, 6559.34406559345, 6562.8687426251445, 6368.363163683641, 6541.247756507991, 6546.770863019771, 6579.769708060243, 6582.341765823403, 5848.093545500455, 5852.209951656557, 5852.76588936442, 5854.297484301868, 5854.912176317359, 23424.531509369717, 23703.644445333335, 23719.0, 23757.16849910245, 24043.639345409832, 2346.691602544647, 2353.458704497958, 2394.150076722756, 2710.5527587948163, 2866.870990805419, 14234.430622775077, 15044.548663540112, 15088.24558772068, 15166.696666066617, 15735.370585176694, 1893.6591413545557, 1920.7096758117139, 1948.2304489726478, 2007.8192962633286, 2015.2342109998253, 80712.78930816043, 82234.0, 83428.0, 87813.0, 87962.56018719866, 11175.720606984727, 11371.886281137082, 11398.373089479992, 11427.200095993367, 11731.296122232694, 16153.596160095856, 16231.107289098976, 16234.75647865283, 16279.186040697918, 16293.592660183353, 7948.483348582324, 8146.144654811278, 8230.547319897463, 8361.790955226046, 8425.831483370299, 7717.305442510201, 7722.884156737653, 7775.922240777633, 7942.602869856484, 7968.282854543003, 4779.139754844162, 4901.877453063631, 4919.385076865392, 5088.160453525127, 5123.97438012815, 1914.2056046740447, 1993.6511110555596, 2026.0232314671778, 2078.9168433262803, 2097.5280561873456, 958.4680502321139, 1011.1405305490274, 1021.6730646193296, 1036.5387402605918, 1042.103790739963, 19386.321478748177, 19846.0, 20011.799882001284, 20090.69863952042, 20153.596928061357, 928.7817362919709, 983.4050399508194, 1014.8832884218261, 1015.6496008876926, 1040.427764729396, 10333.638322658671, 10648.520816563274, 11248.28668480952, 11249.381284029458, 11356.829647555292, 446945.0, 450519.0, 454652.0, 456305.0, 458677.0, 91860.54069729609, 92328.53835730779, 93900.0, 95159.52420237994, 95552.0, 940.4498368454394, 965.8213230552229, 984.675057231112, 985.8570507276548, 990.2820455170088, 16924.915375423043, 17366.82633173652, 18070.5482362942, 18117.45647630573, 18492.167852446404, 43002.35496467493, 43400.34899476447, 43837.78081109574, 44486.11027779491, 44787.328190077824, 63064.36935630769, 63286.36713632989, 64089.67955160194, 64471.677641611495, 64520.0, 8694.17405346491, 8720.912790872264, 8812.206901378906, 8952.328575357005, 9119.817603647758, 12453.315067671003, 13595.864041359855, 13717.245551494365, 13849.86150138526, 13870.167789932637, 11565.132615054074, 11814.232074915102, 12014.819777703517, 12268.263904165779, 12537.185082969578, 11148.219624626197, 11391.0, 11690.88309116932, 11857.976216064848, 12120.818187727367, 10880.891191088305, 11606.24559403636, 11607.0, 11777.0, 12198.207116537396, 8723.08407617191, 8889.64441422336, 8965.372423930523, 9019.774505637411, 9162.267018638626, 10144.64493742745, 11342.376169310766, 11730.472128754056, 11807.350595717318, 11920.582779602672], "value": 9600.90448103285, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1299.9155054921423, 1439.1868594244254, 1476.889233307502, 1568.2472413241644, 1761.7093179625363, 7440.181580026193, 8749.300055995522, 9027.72916812495, 9028.277737780978, 9281.118293762098, 9023.909760902388, 9098.909010909905, 9100.8634870477, 9105.27157827374, 9121.315901307395, 2292.32376448948, 2322.628379459287, 2323.825713071518, 2325.453518423169, 2327.1156960355042, 2353.576356255873, 2382.213869423095, 2426.8301218914594, 2428.6964129483813, 2428.8178386621075, 2370.312609343297, 2384.8688322142275, 2394.8084153267782, 2404.6873906392143, 2412.9758702413014, 2290.2213247495765, 2335.6846825678485, 2341.344423561397, 2343.847649902751, 2358.6226203807482, 2290.484640955788, 2309.872956987365, 2310.5609934112463, 2316.3514216019544, 2318.26974503031, 8959.104089591054, 9081.682141125095, 9103.590338434786, 9216.585253663601, 9222.584983675684, 5279.9208011879855, 5411.621186516945, 5416.133418652997, 5424.647397919122, 5434.673919564839, 3498.860045598173, 3518.0501264658656, 3569.39320315547, 3574.428091505373, 3589.46158076288, 1209.9879001209963, 1216.355331674214, 1233.8951189148934, 1251.5056552661647, 1267.100358745292, 12754.872451275554, 12758.681032974157, 12831.550895718698, 12843.29361885096, 12860.228386296945, 4495.662825288116, 4642.744649044267, 4791.161546729309, 4805.303231031481, 4837.758112094381, 10051.095401413908, 10059.446730429896, 10068.59725610982, 10070.194384449373, 10077.445740484198, 5319.122344813062, 5320.388155362183, 5322.308099947059, 5324.547413469899, 5328.0675881720545, 4317.935230971538, 4804.711717296974, 4934.876628084255, 4961.652684312064, 4977.925331120036, 3811.4282857571034, 3844.2695887781233, 3889.7860617665733, 3958.2083583282883, 3973.0663294125857, 1203.211896207985, 1209.1354681402838, 1216.3127832774453, 1224.4979558381199, 1227.699213692651, 782.4992005116729, 843.9789005274918, 899.3030401438937, 925.9629614815332, 967.1392460709974, 5875.471207591252, 5981.3719559445635, 6118.479929205978, 6151.5386346024225, 6206.548413376691, 453.2933505352351, 469.7838994062701, 481.9975900120477, 486.88071422501713, 488.8142505847826, 3986.7009974252046, 4066.4916885389325, 4082.775447350424, 4128.91742165155, 4208.85269015583, 10757.709074910905, 11221.775564488664, 11359.943200284111, 11447.484863181176, 11448.484818183202, 11166.553337866395, 11338.2630129043, 11459.885401146048, 11468.254563453349, 11473.885261147449, 8347.290480309242, 8432.873506897402, 8447.23974842255, 8448.57757112142, 8457.915420845837, 5749.13762935563, 5785.247917770663, 5828.504577110993, 5883.146943693226, 5935.821925342247, 10512.211584131373, 10523.263371563917, 10532.789344212919, 10554.472276386303, 10582.4708764563, 10188.083072523508, 10600.734981625521, 10613.681589552325, 11002.944985275022, 11005.17461190398, 7045.753398631024, 7904.525728456311, 8109.7161599343735, 8135.349172066339, 8293.419460637939, 8672.392932494664, 8872.556372181494, 8883.511406872683, 8885.733427997171, 8886.866696999421, 7663.501872378278, 7736.419768517271, 7926.524408535276, 8007.679692812222, 8344.413231897888, 7747.883781743166, 7857.096433910174, 7873.881891771743, 7874.488158269694, 7880.645370958436, 7109.82225444368, 7173.24680908518, 7197.280271972971, 7204.027456293543, 7212.639368031682, 5111.437741848319, 5119.74401279942, 5125.974805039082, 5142.331496905378, 5444.183372493999, 5110.897782044264, 5123.38519377677, 5167.819126330561, 5200.141976573975, 5528.281323428089], "value": 4621.856288597104, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.93007500604887, 338.94237979543476, 339.1735504309294, 341.74027738918414, 342.74123037106983, 5079.7968081276795, 5220.694826293427, 5283.867903302415, 5294.549963253128, 5370.91943620846, 5196.662216955895, 5244.0, 5250.501202385767, 5273.734303767097, 5295.920561191576, 4171.6859189355255, 4173.853915112972, 4177.937330940038, 4180.205760905425, 4189.868735441429, 3952.8023598820087, 4083.5294646738594, 4161.354989976543, 4177.979110104445, 4179.874603761892, 4060.5127384713874, 4061.7562946223175, 4068.6948478864206, 4069.857554985576, 4071.0, 4057.5536690963972, 4059.8782036538805, 4062.1266427717887, 4072.0, 4083.9387409188885, 3578.212793185521, 3585.8386372613295, 3591.425371940477, 3594.7663401878795, 3601.9014200668785, 5025.296458495814, 5103.642745007851, 5106.514881086328, 5115.795368185268, 5128.615353848476, 4672.229082201433, 4680.953190468086, 4681.578657920803, 4706.999525201775, 4712.057588482283, 5454.209139674765, 5462.535684466825, 5466.644668096561, 5475.67145971243, 5481.068218402881, 3836.9232615347537, 3976.681865450757, 3979.721419500607, 3989.122393073519, 3997.5802540733093, 5651.886962260773, 5664.490195882349, 5679.359098019399, 5698.373178950312, 5704.77180912763, 4069.674426045909, 4072.490938632671, 4076.5719599442223, 4086.40747091676, 4091.754494730326, 4004.0990777074926, 4087.7138600297694, 4103.2409004334295, 4130.132672138884, 4140.772257525865, 4660.650451216105, 4690.756949408341, 4694.953050469451, 4702.952970470252, 4704.094841589162, 5420.349558053059, 5508.889822203533, 5527.806526771544, 5533.03171944908, 5537.501624853782, 1354.9051566390456, 1371.636516323175, 1405.8734713875801, 1417.9152947994792, 1435.4186554445466, 941.6045260990401, 969.699393188114, 970.1850445625709, 981.8281800684852, 988.7874107067015, 5311.867203319871, 5312.335958005249, 5318.813841515528, 5331.813386531453, 5338.786448542092, 5567.387587365387, 5568.220449137124, 5572.746132120322, 5578.633234857486, 5592.832215033555, 5123.615728820353, 5297.073012222845, 5324.1747529133045, 5332.866678333073, 5350.839474815762, 2649.9400239904194, 2651.668541432321, 2653.5223659741046, 2665.1743555664434, 2668.8532130732606, 4974.228994505857, 5005.649604527721, 5030.396352437658, 5036.823711170092, 5053.84838454847, 4689.273162659793, 4939.802407903644, 5000.824971125993, 5008.29883816266, 5011.448740638527, 5018.770401251643, 5025.542592648091, 5051.419086805065, 5058.873528161752, 5088.109580823415, 4873.952100298453, 4916.901661966812, 4927.841957140141, 4928.802847886044, 4933.728644924564, 4878.243872199815, 5216.9739151304, 5578.959467090526, 5630.324361076697, 5632.324121105494, 5908.822735317947, 5910.881782364243, 5921.703914804329, 5936.851578710394, 5942.940570594239, 4096.6517845982235, 4105.363668631367, 4112.506499219993, 4120.5578047683075, 4120.75275483472, 5416.729163541886, 5444.128939369682, 5655.236543066633, 5682.971585142048, 5709.600327977002, 5411.458854114557, 5463.0, 5518.172274158908, 5551.306086739158, 5559.805406810904, 5509.586780991362, 5528.695921724344, 5535.446455354593, 5539.670479085071, 5539.833804985857, 5307.867303317448, 5479.671219726829, 5482.780688772405, 5495.175723641486, 5519.96401978918, 5572.554195664256, 5582.4138465462165, 5589.524890384363, 5591.776328946959, 5598.272224610774, 5429.484199001088, 5430.348358197041, 5436.9456305438025, 5438.646487978269, 5449.0], "value": 4134.545187456684, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.452614297796, 717.9066721326227, 791.982302740978, 816.9673213071477, 843.8607629741093, 14902.92548537257, 15618.28198898123, 15631.140287284195, 15713.528594142179, 15944.282507287186, 13511.0, 13559.864401355984, 13604.455821767118, 13641.863581364183, 13736.0, 658.7793089315085, 801.4470015689185, 824.1511243419258, 826.8470332988386, 828.581566309013, 674.7166190200128, 677.8949262864264, 681.2063945503508, 794.6026986506747, 816.1960468938091, 854.3208149521146, 862.4135587800293, 865.0830120072743, 865.5672163918041, 865.7013330401002, 856.5246288310017, 858.1632907914795, 858.274757829633, 858.8883445152153, 859.0035558751817, 502.41468688977295, 607.0954278125605, 636.7834936121741, 636.8917284061674, 637.4135795068548, 13209.603711888658, 13441.059125861193, 13915.0, 13992.30038498071, 14126.85873141276, 13981.300934953211, 14763.188024658639, 14829.25853707321, 14883.33025013866, 15016.624584385369, 12969.293451257043, 13202.86797132026, 13349.13230640015, 13630.93184534071, 14030.508932187324, 4490.8877278067985, 4557.635389168859, 4574.519675434065, 4597.908041839144, 4600.9769951150365, 75408.62295688486, 76900.0, 77812.0, 77889.0, 77919.61040194763, 11182.944085279521, 11284.266522676, 11522.654320370402, 11566.595169169206, 11614.825777613343, 21288.574228515343, 21296.041678124522, 21310.467238318855, 21313.573728525655, 21314.761949995336, 8496.40525163245, 8597.5701214939, 8653.740387788377, 8700.738977830675, 8957.372983891195, 4856.538628830255, 4949.90100197994, 4958.504149585013, 5051.722155281494, 5179.015986962465, 3419.1623052352334, 3452.223249768782, 3493.6681015303507, 3500.842462089212, 3520.190356218084, 621.3382747374033, 622.7554441360562, 640.2349192714754, 644.887144749667, 649.383086068237, 555.6971450559455, 592.2567178191342, 598.9161517387569, 648.8345471904634, 653.5294587896707, 9476.241900647932, 9528.809423811485, 9537.14165725088, 9573.856392154123, 9752.0, 543.9129739241703, 620.6865532905854, 648.6983552648041, 670.503827167902, 672.1564436632093, 4471.575200355961, 4584.7936842842155, 4675.378923499243, 4789.880252993703, 4899.510048995072, 121348.0, 124725.75274247142, 130207.0, 131736.0, 134545.0, 105358.0, 109122.45438772756, 110847.0, 111702.0, 116657.0, 92744.0, 96084.51957740166, 97395.0, 97712.02287977171, 98850.50574747224, 8833.955830220808, 9116.361854670375, 9179.495127768037, 9181.540922953693, 9204.631814727603, 52600.47399526109, 54495.27799580987, 54605.0, 54743.967245405656, 59660.40339596722, 50980.49019509906, 57949.71025144847, 63181.68409157925, 63585.68207158935, 65790.34209657842, 13553.661158470723, 13682.110662806886, 13738.244396558286, 13901.791473128114, 14459.0, 14969.476068337555, 15224.619384515476, 15245.695086098438, 15453.768193476883, 15621.297041632926, 14139.36372863203, 14447.78328325055, 14468.27658617086, 14614.415423382945, 15122.54632361031, 13999.930000349934, 14066.156030638194, 14078.788818167941, 14415.639609009859, 14574.927125364306, 12953.740925181632, 13234.470621175431, 13356.465741370625, 13409.731805364034, 14186.716265674835, 10733.731656708645, 10992.780144397228, 11026.393548354918, 11347.900638381925, 11523.481443335239, 10937.507812148313, 11436.313821170756, 11441.942790285995, 11458.369789661649, 11666.416679166177], "value": 7599.66629049125, "unit": "score"}], "name": "dromaeo_css", "value": 7311.149921143946, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 112.0, 128.0, 111.0, 116.0, 109.0, 111.0, 112.0, 111.0, 113.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 116.0, 143.0, 179.0, 121.0, 153.0, 152.0, 123.0, 189.0, 120.0], "value": 144.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 162.0, 162.0, 162.0, 162.0, 160.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 168.0, 77.0, 77.0, 74.0, 76.0, 163.0, 76.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 160.0, 86.0, 85.0, 85.0, 144.0, 85.0, 86.0, 144.0], "value": 104.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 52.0, 52.0, 52.0, 54.0, 59.0, 52.0, 55.0, 58.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 61.0, 62.0, 63.0, 63.0, 65.0, 65.0, 64.0], "value": 63.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 81.0, 78.0, 82.0, 87.0, 83.0, 78.0, 84.0, 79.0, 79.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 122.0, 121.0, 131.0, 125.0, 123.0, 124.0, 125.0, 153.0, 129.0], "value": 128.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 162.0, 164.0, 165.0, 161.0, 162.0, 161.0, 161.0, 161.0], "value": 162.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 65.0, 67.0, 68.0, 68.0, 64.0, 66.0, 67.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1531.8000000000002, "alertThreshold": 2.0}]} 06:19:27 INFO - Return code: 0 06:19:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:19:27 INFO - # TBPL SUCCESS # 06:19:27 INFO - Running post-action listener: _resource_record_post_action 06:19:27 INFO - [mozharness: 2016-08-24 13:19:27.715761Z] Finished run-tests step (success) 06:19:27 INFO - Running post-run listener: _resource_record_post_run 06:19:27 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 417759232; Read time: 16; Write time: 322892 06:19:27 INFO - TinderboxPrint: CPU usage
13.3% 06:19:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 06:19:27 INFO - TinderboxPrint: I/O write bytes / time
417,759,232 / 322,892 06:19:27 INFO - TinderboxPrint: CPU idle
8,285.2 (86.7%) 06:19:27 INFO - TinderboxPrint: CPU user
1,203.1 (12.6%) 06:19:27 INFO - TinderboxPrint: Swap in / out
0 / 0 06:19:27 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 128798720; Read time: 0; Write time: 87084 06:19:27 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 0; Write bytes: 288018432; Read time: 0; Write time: 235784 06:19:28 INFO - Running post-run listener: _upload_blobber_files 06:19:28 INFO - Blob upload gear active. 06:19:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:19:28 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 06:19:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:19:28 INFO - (blobuploader) - INFO - Open directory for files ... 06:19:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:19:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:19:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:29 INFO - (blobuploader) - INFO - Done attempting. 06:19:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:19:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:19:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:33 INFO - (blobuploader) - INFO - Done attempting. 06:19:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:19:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:19:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:34 INFO - (blobuploader) - INFO - Done attempting. 06:19:34 INFO - (blobuploader) - INFO - Iteration through files over. 06:19:34 INFO - Return code: 0 06:19:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:19:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:19:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4eafc53331efac10fdae8426bf5a3f4c05929567ed2da057ade74f9f80a0cdbb4b1ef2a85b85c4a1d71464097b3329a18780b9972ecc7a55c396795047148750", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66507680672741173c2211a767b39e65d68067d926c0ef9c20ee3649519d3271886594bbdfbb0d45c79d7919499cdbf65866aa0f297b72146342b9235d857204", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c55c1067c8a30e3d3a4fbe5abf6f21d887fd20c0c1e93f9dbe32b22bcfb5f8cad8fb5c556a2216d3ba6933eb22c43bd894e73548fc5a28a1a98b9027c692bf7d"} 06:19:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:19:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:19:34 INFO - Contents: 06:19:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4eafc53331efac10fdae8426bf5a3f4c05929567ed2da057ade74f9f80a0cdbb4b1ef2a85b85c4a1d71464097b3329a18780b9972ecc7a55c396795047148750", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66507680672741173c2211a767b39e65d68067d926c0ef9c20ee3649519d3271886594bbdfbb0d45c79d7919499cdbf65866aa0f297b72146342b9235d857204", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c55c1067c8a30e3d3a4fbe5abf6f21d887fd20c0c1e93f9dbe32b22bcfb5f8cad8fb5c556a2216d3ba6933eb22c43bd894e73548fc5a28a1a98b9027c692bf7d"} 06:19:34 INFO - Running post-run listener: copy_logs_to_upload_dir 06:19:34 INFO - Copying logs to upload dir... 06:19:34 INFO - mkdir: /builds/slave/test/build/upload/logs 06:19:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.219340 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-08-24 06:19:34.135063) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-24 06:19:34.139414) ========= 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=4300f881736b422476174874000001bc-1472042438.804563-876286220 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4eafc53331efac10fdae8426bf5a3f4c05929567ed2da057ade74f9f80a0cdbb4b1ef2a85b85c4a1d71464097b3329a18780b9972ecc7a55c396795047148750", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66507680672741173c2211a767b39e65d68067d926c0ef9c20ee3649519d3271886594bbdfbb0d45c79d7919499cdbf65866aa0f297b72146342b9235d857204", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c55c1067c8a30e3d3a4fbe5abf6f21d887fd20c0c1e93f9dbe32b22bcfb5f8cad8fb5c556a2216d3ba6933eb22c43bd894e73548fc5a28a1a98b9027c692bf7d"} build_url:https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011360 build_url: 'https://queue.taskcluster.net/v1/task/K1WMg3AaS3CMczJMlb04WA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4eafc53331efac10fdae8426bf5a3f4c05929567ed2da057ade74f9f80a0cdbb4b1ef2a85b85c4a1d71464097b3329a18780b9972ecc7a55c396795047148750", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66507680672741173c2211a767b39e65d68067d926c0ef9c20ee3649519d3271886594bbdfbb0d45c79d7919499cdbf65866aa0f297b72146342b9235d857204", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c55c1067c8a30e3d3a4fbe5abf6f21d887fd20c0c1e93f9dbe32b22bcfb5f8cad8fb5c556a2216d3ba6933eb22c43bd894e73548fc5a28a1a98b9027c692bf7d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-24 06:19:34.167355) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 06:19:34.167685) ========= 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=4300f881736b422476174874000001bc-1472042438.804563-876286220 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 06:19:34.246702) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-24 06:19:34.250028) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-24 06:19:34.250324) ========= ========= Total master_lag: 0.18 =========