builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-029 starttime: 1485236861.85 results: success (0) buildid: 20170123195043 builduid: 073220fafa584cdeb7fa162cb82c1601 revision: 7e83a181036b71708716f64d644f10b38a479e69 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-23 21:47:41.854419) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-23 21:47:41.854877) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-23 21:47:41.855216) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-23 21:47:41.869553) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 21:47:41.869824) ========= 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=ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-23 21:47:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.7M=0s 2017-01-23 21:47:42 (40.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.109710 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 21:47:41.993162) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 21:47:41.993498) ========= 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=ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017946 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 21:47:42.042611) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-23 21:47:42.042988) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7e83a181036b71708716f64d644f10b38a479e69 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7e83a181036b71708716f64d644f10b38a479e69 --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=ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-23 21:47:42,418 truncating revision to first 12 chars 2017-01-23 21:47:42,418 Setting DEBUG logging. 2017-01-23 21:47:42,418 attempt 1/10 2017-01-23 21:47:42,418 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/7e83a181036b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-23 21:47:43,855 unpacking tar archive at: mozilla-beta-7e83a181036b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.719676 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-23 21:47:43.776102) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-23 21:47:43.776427) ========= script_repo_revision: 7e83a181036b71708716f64d644f10b38a479e69 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-23 21:47:43.776830) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-23 21:47:43.777095) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-23 21:47:43.785169) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2017-01-23 21:47:43.785535) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:47:44 INFO - MultiFileLogger online at 20170123 21:47:44 in /builds/slave/test 21:47:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 21:47:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:47:44 INFO - {'append_to_log': False, 21:47:44 INFO - 'base_work_dir': '/builds/slave/test', 21:47:44 INFO - 'blob_upload_branch': 'Mozilla-Beta', 21:47:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:47:44 INFO - 'branch': 'Mozilla-Beta', 21:47:44 INFO - 'buildbot_json_path': 'buildprops.json', 21:47:44 INFO - 'config_files': ('talos/linux_config.py',), 21:47:44 INFO - 'default_actions': ('clobber', 21:47:44 INFO - 'read-buildbot-config', 21:47:44 INFO - 'download-and-extract', 21:47:44 INFO - 'populate-webroot', 21:47:44 INFO - 'create-virtualenv', 21:47:44 INFO - 'install', 21:47:44 INFO - 'run-tests'), 21:47:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:47:44 INFO - 'download_minidump_stackwalk': True, 21:47:44 INFO - 'download_symbols': 'ondemand', 21:47:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:47:44 INFO - 'tooltool.py': '/tools/tooltool.py', 21:47:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:47:44 INFO - '/tools/misc-python/virtualenv.py')}, 21:47:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:47:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:47:44 INFO - 'installer_path': 'installer.exe', 21:47:44 INFO - 'log_level': 'info', 21:47:44 INFO - 'log_name': 'talos', 21:47:44 INFO - 'log_to_console': True, 21:47:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:47:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:47:44 INFO - 'opt_config_files': (), 21:47:44 INFO - 'pip_index': False, 21:47:44 INFO - 'sps_profile': False, 21:47:44 INFO - 'sps_profile_interval': 0, 21:47:44 INFO - 'suite': 'dromaeojs', 21:47:44 INFO - 'system_bits': '32', 21:47:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:47:44 INFO - 'title': 'talos-linux64-ix-029', 21:47:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:47:44 INFO - 'use_talos_json': True, 21:47:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:47:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:47:44 INFO - 'work_dir': 'build'} 21:47:44 INFO - [mozharness: 2017-01-24 05:47:44.253950Z] Running clobber step. 21:47:44 INFO - Running pre-action listener: _resource_record_pre_action 21:47:44 INFO - Running main action method: clobber 21:47:44 INFO - rmtree: /builds/slave/test/build 21:47:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:47:45 INFO - Running post-action listener: _resource_record_post_action 21:47:45 INFO - [mozharness: 2017-01-24 05:47:45.272941Z] Finished clobber step (success) 21:47:45 INFO - [mozharness: 2017-01-24 05:47:45.273040Z] Running read-buildbot-config step. 21:47:45 INFO - Running pre-action listener: _resource_record_pre_action 21:47:45 INFO - Running main action method: read_buildbot_config 21:47:45 INFO - Using buildbot properties: 21:47:45 INFO - { 21:47:45 INFO - "project": "", 21:47:45 INFO - "product": "firefox", 21:47:45 INFO - "script_repo_revision": "production", 21:47:45 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 21:47:45 INFO - "repository": "", 21:47:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 21:47:45 INFO - "buildid": "20170123195043", 21:47:45 INFO - "pgo_build": "True", 21:47:45 INFO - "basedir": "/builds/slave/test", 21:47:45 INFO - "buildnumber": 521, 21:47:45 INFO - "slavename": "talos-linux64-ix-029", 21:47:45 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:47:45 INFO - "platform": "ubuntu64_hw", 21:47:45 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 21:47:45 INFO - "branch": "mozilla-beta", 21:47:45 INFO - "repo_path": "releases/mozilla-beta", 21:47:45 INFO - "revision": "7e83a181036b71708716f64d644f10b38a479e69", 21:47:45 INFO - "stage_platform": "linux64-pgo", 21:47:45 INFO - "builduid": "073220fafa584cdeb7fa162cb82c1601", 21:47:45 INFO - "slavebuilddir": "test" 21:47:45 INFO - } 21:47:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2. 21:47:45 INFO - Running post-action listener: _resource_record_post_action 21:47:45 INFO - [mozharness: 2017-01-24 05:47:45.275504Z] Finished read-buildbot-config step (success) 21:47:45 INFO - [mozharness: 2017-01-24 05:47:45.275593Z] Running download-and-extract step. 21:47:45 INFO - Running pre-action listener: _resource_record_pre_action 21:47:45 INFO - Running main action method: download_and_extract 21:47:45 INFO - mkdir: /builds/slave/test/build/tests 21:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:45 INFO - trying https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 21:47:45 INFO - Downloading https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 21:47:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:47:45 INFO - Downloaded 1327 bytes. 21:47:45 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 21:47:45 INFO - Using the following test package requirements: 21:47:45 INFO - {u'common': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 21:47:45 INFO - u'cppunittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 21:47:45 INFO - u'firefox-52.0.en-US.linux-x86_64.cppunittest.tests.zip'], 21:47:45 INFO - u'gtest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 21:47:45 INFO - u'firefox-52.0.en-US.linux-x86_64.gtest.tests.zip'], 21:47:45 INFO - u'jittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 21:47:45 INFO - u'jsshell-linux-x86_64.zip'], 21:47:45 INFO - u'mochitest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 21:47:45 INFO - u'firefox-52.0.en-US.linux-x86_64.mochitest.tests.zip'], 21:47:45 INFO - u'mozbase': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 21:47:45 INFO - u'reftest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 21:47:45 INFO - u'firefox-52.0.en-US.linux-x86_64.reftest.tests.zip'], 21:47:45 INFO - u'talos': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 21:47:45 INFO - u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'], 21:47:45 INFO - u'web-platform': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 21:47:45 INFO - u'firefox-52.0.en-US.linux-x86_64.web-platform.tests.zip'], 21:47:45 INFO - u'xpcshell': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 21:47:45 INFO - u'firefox-52.0.en-US.linux-x86_64.xpcshell.tests.zip']} 21:47:45 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:47:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 21:47:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:47:45 INFO - Fetch https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 21:47:46 INFO - Http code: 200 21:47:46 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 21:47:46 INFO - x-amz-cf-id: yVKem-aJGZ2r9ji5_8NTAH8eEhQgerGcf_OhPXgKurY-BaP2QRyBVQ== 21:47:46 INFO - x-amz-version-id: iBbj7UckmJnk39IYeiBfqMy1pz7TXznM 21:47:46 INFO - Expected file size: 21949134 21:47:46 INFO - Obtained file size: 21949134 21:47:51 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:47:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 21:47:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:47:51 INFO - Fetch https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 21:47:51 INFO - Http code: 200 21:47:51 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 21:47:51 INFO - x-amz-cf-id: CPGbSjE-_4PPEBCG_5Tq3LJH6-PuxaGnMKmc2Zn0M4AHTn6r1E6aAQ== 21:47:51 INFO - x-amz-version-id: iBbj7UckmJnk39IYeiBfqMy1pz7TXznM 21:47:51 INFO - Expected file size: 21949134 21:47:51 INFO - Obtained file size: 21949134 21:47:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip 21:47:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:47:56 INFO - Fetch https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip into memory 21:47:56 INFO - Http code: 200 21:47:56 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 21:47:56 INFO - x-amz-cf-id: YXfsxeCh9G7qQgbF4xAsAB-UBKdTsSi5unlkmhLsQGo_jz_F-CpgeA== 21:47:56 INFO - x-amz-version-id: 7DznCMjM0GHrvek.WhptZBodRNyOrID1 21:47:57 INFO - Expected file size: 14402992 21:47:57 INFO - Obtained file size: 14402992 21:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:57 INFO - trying https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 21:47:57 INFO - Downloading https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 21:47:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:47:58 INFO - Downloaded 57689042 bytes. 21:47:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 21:47:58 INFO - mkdir: /builds/slave/test/properties 21:47:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:47:58 INFO - Writing to file /builds/slave/test/properties/build_url 21:47:58 INFO - Contents: 21:47:58 INFO - build_url:https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 21:47:59 INFO - Running post-action listener: _resource_record_post_action 21:47:59 INFO - Running post-action listener: set_extra_try_arguments 21:47:59 INFO - [mozharness: 2017-01-24 05:47:59.118160Z] Finished download-and-extract step (success) 21:47:59 INFO - [mozharness: 2017-01-24 05:47:59.118389Z] Running populate-webroot step. 21:47:59 INFO - Running pre-action listener: _resource_record_pre_action 21:47:59 INFO - Running main action method: populate_webroot 21:47:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:47:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:47:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:47:59 INFO - '--tppagecycles', 21:47:59 INFO - '7'], 21:47:59 INFO - 'tests': ['tsvgm']}}, 21:47:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:47:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:47:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:47:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:47:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:47:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:47:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:59 INFO - 'talos_options': ['--disable-e10s'], 21:47:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:47:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:47:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:59 INFO - 'talos_options': ['--disable-e10s'], 21:47:59 INFO - 'tests': ['damp', 'tps']}, 21:47:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:59 INFO - 'tests': ['damp', 'tps']}, 21:47:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:47:59 INFO - 'tests': ['dromaeo_dom']}, 21:47:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:47:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:47:59 INFO - 'tests': ['basic_compositor_video']}, 21:47:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:47:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:47:59 INFO - 'tests': ['a11yr', 21:47:59 INFO - 'ts_paint', 21:47:59 INFO - 'tpaint', 21:47:59 INFO - 'sessionrestore', 21:47:59 INFO - 'sessionrestore_no_auto_restore', 21:47:59 INFO - 'tabpaint']}, 21:47:59 INFO - 'other-e10s': {'tests': ['a11yr', 21:47:59 INFO - 'ts_paint', 21:47:59 INFO - 'tpaint', 21:47:59 INFO - 'sessionrestore', 21:47:59 INFO - 'sessionrestore_no_auto_restore', 21:47:59 INFO - 'tabpaint']}, 21:47:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:47:59 INFO - 'ts_paint', 21:47:59 INFO - 'tpaint', 21:47:59 INFO - 'sessionrestore', 21:47:59 INFO - 'sessionrestore_no_auto_restore', 21:47:59 INFO - 'tabpaint']}, 21:47:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:47:59 INFO - 'ts_paint', 21:47:59 INFO - 'tpaint', 21:47:59 INFO - 'sessionrestore', 21:47:59 INFO - 'sessionrestore_no_auto_restore', 21:47:59 INFO - 'tabpaint']}, 21:47:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:47:59 INFO - 'tests': ['a11yr', 21:47:59 INFO - 'ts_paint', 21:47:59 INFO - 'tpaint', 21:47:59 INFO - 'sessionrestore', 21:47:59 INFO - 'sessionrestore_no_auto_restore', 21:47:59 INFO - 'tabpaint']}, 21:47:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:47:59 INFO - 'tests': ['a11yr', 21:47:59 INFO - 'ts_paint', 21:47:59 INFO - 'tpaint', 21:47:59 INFO - 'sessionrestore', 21:47:59 INFO - 'sessionrestore_no_auto_restore', 21:47:59 INFO - 'tabpaint']}, 21:47:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:47:59 INFO - 'tests': ['tsvgx', 21:47:59 INFO - 'tsvgr_opacity', 21:47:59 INFO - 'tart', 21:47:59 INFO - 'tscrollx', 21:47:59 INFO - 'cart']}, 21:47:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:47:59 INFO - 'tsvgr_opacity', 21:47:59 INFO - 'tart', 21:47:59 INFO - 'tscrollx', 21:47:59 INFO - 'cart']}, 21:47:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:59 INFO - 'talos_options': ['--disable-e10s'], 21:47:59 INFO - 'tests': ['tp5o']}, 21:47:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:59 INFO - 'tests': ['tp5o']}, 21:47:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:59 INFO - 'talos_options': ['--disable-e10s', 21:47:59 INFO - '--xperf_path', 21:47:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:47:59 INFO - 'tests': ['tp5n']}, 21:47:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:59 INFO - 'talos_options': ['--xperf_path', 21:47:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:47:59 INFO - 'tests': ['tp5n']}}, 21:47:59 INFO - 'talos.zip': {'path': '', 21:47:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:47:59 INFO - Running post-action listener: _resource_record_post_action 21:47:59 INFO - [mozharness: 2017-01-24 05:47:59.138884Z] Finished populate-webroot step (success) 21:47:59 INFO - [mozharness: 2017-01-24 05:47:59.138974Z] Running create-virtualenv step. 21:47:59 INFO - Running pre-action listener: _resource_record_pre_action 21:47:59 INFO - Running main action method: create_virtualenv 21:47:59 INFO - Creating virtualenv /builds/slave/test/build/venv 21:47:59 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 21:47:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:47:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:47:59 INFO - Using real prefix '/usr' 21:47:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:47:59 INFO - Installing distribute.............................................................................................................................................................................................done. 21:48:01 INFO - Installing pip.................done. 21:48:01 INFO - Return code: 0 21:48:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:48:01 INFO - Reading from file tmpfile_stdout 21:48:01 INFO - Output received: 21:48:01 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:48:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:48:01 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')]} 21:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:01 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')]} 21:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:01 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 0x7ffbc7e27f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x256fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256cf60>, '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 0x256dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x256e1d0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', '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 21:48:01 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 21:48:01 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 21:48:01 INFO - Using env: {'DISPLAY': ':0', 21:48:01 INFO - 'HOME': '/home/cltbld', 21:48:01 INFO - 'LANG': 'en_US.UTF-8', 21:48:01 INFO - 'LANGUAGE': 'en_US:en', 21:48:01 INFO - 'LOGNAME': 'cltbld', 21:48:01 INFO - 'MAIL': '/var/mail/cltbld', 21:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:01 INFO - 'MOZ_NO_REMOTE': '1', 21:48:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:01 INFO - 'NO_EM_RESTART': '1', 21:48:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:01 INFO - 'PWD': '/builds/slave/test', 21:48:01 INFO - 'SHELL': '/bin/bash', 21:48:01 INFO - 'SHLVL': '1', 21:48:01 INFO - 'TERM': 'linux', 21:48:01 INFO - 'TMOUT': '86400', 21:48:01 INFO - 'USER': 'cltbld', 21:48:01 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', 21:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:01 INFO - '_': '/tools/buildbot/bin/python'} 21:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:02 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 21:48:02 INFO - Cleaning up... 21:48:02 INFO - Return code: 0 21:48:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:48:02 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')]} 21:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:02 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')]} 21:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:02 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 0x7ffbc7e27f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x256fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256cf60>, '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 0x256dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x256e1d0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', '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 21:48:02 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 21:48:02 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 21:48:02 INFO - Using env: {'DISPLAY': ':0', 21:48:02 INFO - 'HOME': '/home/cltbld', 21:48:02 INFO - 'LANG': 'en_US.UTF-8', 21:48:02 INFO - 'LANGUAGE': 'en_US:en', 21:48:02 INFO - 'LOGNAME': 'cltbld', 21:48:02 INFO - 'MAIL': '/var/mail/cltbld', 21:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:02 INFO - 'MOZ_NO_REMOTE': '1', 21:48:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:02 INFO - 'NO_EM_RESTART': '1', 21:48:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:02 INFO - 'PWD': '/builds/slave/test', 21:48:02 INFO - 'SHELL': '/bin/bash', 21:48:02 INFO - 'SHLVL': '1', 21:48:02 INFO - 'TERM': 'linux', 21:48:02 INFO - 'TMOUT': '86400', 21:48:02 INFO - 'USER': 'cltbld', 21:48:02 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', 21:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:02 INFO - '_': '/tools/buildbot/bin/python'} 21:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:02 INFO - Downloading/unpacking psutil>=3.1.1 21:48:02 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 21:48:02 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 21:48:02 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 21:48:02 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 21:48:02 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 21:48:02 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 21:48:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:48:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:48:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:48:04 INFO - Installing collected packages: psutil 21:48:04 INFO - Running setup.py install for psutil 21:48:04 INFO - building 'psutil._psutil_linux' extension 21:48:04 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 21:48:04 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 21:48:04 INFO - building 'psutil._psutil_posix' extension 21:48:04 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 21:48:04 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 21:48:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:48:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:48:05 INFO - Successfully installed psutil 21:48:05 INFO - Cleaning up... 21:48:05 INFO - Return code: 0 21:48:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:48: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')]} 21:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48: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')]} 21:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:05 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 0x7ffbc7e27f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x256fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256cf60>, '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 0x256dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x256e1d0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', '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 21:48:05 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 21:48:05 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 21:48:05 INFO - Using env: {'DISPLAY': ':0', 21:48:05 INFO - 'HOME': '/home/cltbld', 21:48:05 INFO - 'LANG': 'en_US.UTF-8', 21:48:05 INFO - 'LANGUAGE': 'en_US:en', 21:48:05 INFO - 'LOGNAME': 'cltbld', 21:48:05 INFO - 'MAIL': '/var/mail/cltbld', 21:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:05 INFO - 'MOZ_NO_REMOTE': '1', 21:48:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:05 INFO - 'NO_EM_RESTART': '1', 21:48:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:05 INFO - 'PWD': '/builds/slave/test', 21:48:05 INFO - 'SHELL': '/bin/bash', 21:48:05 INFO - 'SHLVL': '1', 21:48:05 INFO - 'TERM': 'linux', 21:48:05 INFO - 'TMOUT': '86400', 21:48:05 INFO - 'USER': 'cltbld', 21:48:05 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', 21:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:05 INFO - '_': '/tools/buildbot/bin/python'} 21:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:48:05 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 21:48:05 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 21:48:05 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 21:48:05 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 21:48:05 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 21:48:05 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 21:48:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:48:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:48:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:48:07 INFO - Installing collected packages: mozsystemmonitor 21:48:07 INFO - Running setup.py install for mozsystemmonitor 21:48:07 INFO - Successfully installed mozsystemmonitor 21:48:07 INFO - Cleaning up... 21:48:07 INFO - Return code: 0 21:48:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:48:07 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')]} 21:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:07 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')]} 21:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:07 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 0x7ffbc7e27f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x256fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256cf60>, '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 0x256dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x256e1d0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', '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 21:48:07 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 21:48:07 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 21:48:07 INFO - Using env: {'DISPLAY': ':0', 21:48:07 INFO - 'HOME': '/home/cltbld', 21:48:07 INFO - 'LANG': 'en_US.UTF-8', 21:48:07 INFO - 'LANGUAGE': 'en_US:en', 21:48:07 INFO - 'LOGNAME': 'cltbld', 21:48:07 INFO - 'MAIL': '/var/mail/cltbld', 21:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:07 INFO - 'MOZ_NO_REMOTE': '1', 21:48:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:07 INFO - 'NO_EM_RESTART': '1', 21:48:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:07 INFO - 'PWD': '/builds/slave/test', 21:48:07 INFO - 'SHELL': '/bin/bash', 21:48:07 INFO - 'SHLVL': '1', 21:48:07 INFO - 'TERM': 'linux', 21:48:07 INFO - 'TMOUT': '86400', 21:48:07 INFO - 'USER': 'cltbld', 21:48:07 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', 21:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:07 INFO - '_': '/tools/buildbot/bin/python'} 21:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:07 INFO - Downloading/unpacking jsonschema==2.5.1 21:48:07 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 21:48:07 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 21:48:07 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 21:48:07 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 21:48:07 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 21:48:07 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 21:48:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:48:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:48:09 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 21:48:09 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 21:48:09 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 21:48:09 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 21:48:09 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 21:48:09 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 21:48:09 INFO - Downloading functools32-3.2.3-2.tar.gz 21:48:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:48:10 INFO - warning: no files found matching '*.txt' 21:48:10 INFO - no previously-included directories found matching 'build' 21:48:10 INFO - no previously-included directories found matching 'dist' 21:48:10 INFO - no previously-included directories found matching '.git*' 21:48:10 INFO - Installing collected packages: jsonschema, functools32 21:48:10 INFO - Running setup.py install for functools32 21:48:10 INFO - warning: no files found matching '*.txt' 21:48:10 INFO - no previously-included directories found matching 'build' 21:48:10 INFO - no previously-included directories found matching 'dist' 21:48:10 INFO - no previously-included directories found matching '.git*' 21:48:10 INFO - Successfully installed jsonschema functools32 21:48:10 INFO - Cleaning up... 21:48:10 INFO - Return code: 0 21:48:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:48: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')]} 21:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48: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')]} 21:48:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48: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', '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 0x7ffbc7e27f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x256fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256cf60>, '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 0x256dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x256e1d0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', '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 21:48: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:48: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 functools32==3.2.3-2 21:48:10 INFO - Using env: {'DISPLAY': ':0', 21:48:10 INFO - 'HOME': '/home/cltbld', 21:48:10 INFO - 'LANG': 'en_US.UTF-8', 21:48:10 INFO - 'LANGUAGE': 'en_US:en', 21:48:10 INFO - 'LOGNAME': 'cltbld', 21:48:10 INFO - 'MAIL': '/var/mail/cltbld', 21:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:10 INFO - 'MOZ_NO_REMOTE': '1', 21:48:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:10 INFO - 'NO_EM_RESTART': '1', 21:48:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:10 INFO - 'PWD': '/builds/slave/test', 21:48:10 INFO - 'SHELL': '/bin/bash', 21:48:10 INFO - 'SHLVL': '1', 21:48:10 INFO - 'TERM': 'linux', 21:48:10 INFO - 'TMOUT': '86400', 21:48:10 INFO - 'USER': 'cltbld', 21:48:10 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', 21:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:10 INFO - '_': '/tools/buildbot/bin/python'} 21:48:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:48:10 INFO - Cleaning up... 21:48:10 INFO - Return code: 0 21:48:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:48: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')]} 21:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48: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')]} 21:48:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48: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', '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 0x7ffbc7e27f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x256fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256cf60>, '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 0x256dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x256e1d0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', '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 21:48: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:48: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 blobuploader==1.2.4 21:48:10 INFO - Using env: {'DISPLAY': ':0', 21:48:10 INFO - 'HOME': '/home/cltbld', 21:48:10 INFO - 'LANG': 'en_US.UTF-8', 21:48:10 INFO - 'LANGUAGE': 'en_US:en', 21:48:10 INFO - 'LOGNAME': 'cltbld', 21:48:10 INFO - 'MAIL': '/var/mail/cltbld', 21:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:10 INFO - 'MOZ_NO_REMOTE': '1', 21:48:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:10 INFO - 'NO_EM_RESTART': '1', 21:48:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:10 INFO - 'PWD': '/builds/slave/test', 21:48:10 INFO - 'SHELL': '/bin/bash', 21:48:10 INFO - 'SHLVL': '1', 21:48:10 INFO - 'TERM': 'linux', 21:48:10 INFO - 'TMOUT': '86400', 21:48:10 INFO - 'USER': 'cltbld', 21:48:10 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', 21:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:10 INFO - '_': '/tools/buildbot/bin/python'} 21:48:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:10 INFO - Downloading/unpacking blobuploader==1.2.4 21:48: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 21:48: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 21:48: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 21:48: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 21:48: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 21:48: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 21:48:12 INFO - Downloading blobuploader-1.2.4.tar.gz 21:48:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:48:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:48:12 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 21:48:12 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 21:48:12 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 21:48:12 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 21:48:12 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 21:48:12 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 21:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:48:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:48: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 21:48: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 21:48: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 21:48: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 21:48: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 21:48: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 21:48:13 INFO - Downloading docopt-0.6.1.tar.gz 21:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:48:13 INFO - Installing collected packages: blobuploader, requests, docopt 21:48:13 INFO - Running setup.py install for blobuploader 21:48:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:48:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:48:13 INFO - Running setup.py install for requests 21:48:13 INFO - Running setup.py install for docopt 21:48:14 INFO - Successfully installed blobuploader requests docopt 21:48:14 INFO - Cleaning up... 21:48:14 INFO - Return code: 0 21:48:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:14 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 0x7ffbc7e27f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x256fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256cf60>, '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 0x256dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x256e1d0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', '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 21:48:14 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 21:48:14 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 21:48:14 INFO - Using env: {'DISPLAY': ':0', 21:48:14 INFO - 'HOME': '/home/cltbld', 21:48:14 INFO - 'LANG': 'en_US.UTF-8', 21:48:14 INFO - 'LANGUAGE': 'en_US:en', 21:48:14 INFO - 'LOGNAME': 'cltbld', 21:48:14 INFO - 'MAIL': '/var/mail/cltbld', 21:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:14 INFO - 'MOZ_NO_REMOTE': '1', 21:48:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:14 INFO - 'NO_EM_RESTART': '1', 21:48:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:14 INFO - 'PWD': '/builds/slave/test', 21:48:14 INFO - 'SHELL': '/bin/bash', 21:48:14 INFO - 'SHLVL': '1', 21:48:14 INFO - 'TERM': 'linux', 21:48:14 INFO - 'TMOUT': '86400', 21:48:14 INFO - 'USER': 'cltbld', 21:48:14 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', 21:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:14 INFO - '_': '/tools/buildbot/bin/python'} 21:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:48:14 INFO - Running setup.py (path:/tmp/pip-gg6l5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:48:14 INFO - Running setup.py (path:/tmp/pip-IdwGJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:48:14 INFO - Running setup.py (path:/tmp/pip-Ao_sn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:48:14 INFO - Running setup.py (path:/tmp/pip-0w5pt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:48:14 INFO - Running setup.py (path:/tmp/pip-lUGwWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:48:14 INFO - Running setup.py (path:/tmp/pip-TpwLus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:48:14 INFO - Running setup.py (path:/tmp/pip-VZWDYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:48:14 INFO - Running setup.py (path:/tmp/pip-LXGAdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:48:14 INFO - Running setup.py (path:/tmp/pip-iKAerW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:48:14 INFO - Running setup.py (path:/tmp/pip-d4bht3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:48:15 INFO - Running setup.py (path:/tmp/pip-X4y9kJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:48:15 INFO - Running setup.py (path:/tmp/pip-UeEepv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:48:15 INFO - Running setup.py (path:/tmp/pip-oTtHEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:48:15 INFO - Running setup.py (path:/tmp/pip-Zr58_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:15 INFO - Running setup.py (path:/tmp/pip-_ybBUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:48:15 INFO - Running setup.py (path:/tmp/pip-pg6Lj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:48:15 INFO - Running setup.py (path:/tmp/pip-qVHaiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:48:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:48:15 INFO - Running setup.py install for manifestparser 21:48:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:48:15 INFO - Running setup.py install for mozcrash 21:48:15 INFO - Running setup.py install for mozdebug 21:48:15 INFO - Running setup.py install for mozdevice 21:48:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:48:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:48:15 INFO - Running setup.py install for mozfile 21:48:16 INFO - Running setup.py install for mozhttpd 21:48:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:48:16 INFO - Running setup.py install for mozinfo 21:48:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:48:16 INFO - Running setup.py install for mozInstall 21:48:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:48:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:48:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:48:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:48:16 INFO - Running setup.py install for mozleak 21:48:16 INFO - Running setup.py install for mozlog 21:48:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:48:16 INFO - Running setup.py install for moznetwork 21:48:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:48:16 INFO - Running setup.py install for mozprocess 21:48:16 INFO - Running setup.py install for mozprofile 21:48:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:48:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:48:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:48:16 INFO - Running setup.py install for mozrunner 21:48:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:48:17 INFO - Running setup.py install for mozscreenshot 21:48:17 INFO - Running setup.py install for moztest 21:48:17 INFO - Running setup.py install for mozversion 21:48:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:48:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:48:17 INFO - Cleaning up... 21:48:17 INFO - Return code: 0 21:48:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48: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')]} 21:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48: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')]} 21:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:17 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 0x7ffbc7e27f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x256fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256cf60>, '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 0x256dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x256e1d0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', '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 21:48:17 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 21:48:17 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 21:48:17 INFO - Using env: {'DISPLAY': ':0', 21:48:17 INFO - 'HOME': '/home/cltbld', 21:48:17 INFO - 'LANG': 'en_US.UTF-8', 21:48:17 INFO - 'LANGUAGE': 'en_US:en', 21:48:17 INFO - 'LOGNAME': 'cltbld', 21:48:17 INFO - 'MAIL': '/var/mail/cltbld', 21:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:17 INFO - 'MOZ_NO_REMOTE': '1', 21:48:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:17 INFO - 'NO_EM_RESTART': '1', 21:48:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:17 INFO - 'PWD': '/builds/slave/test', 21:48:17 INFO - 'SHELL': '/bin/bash', 21:48:17 INFO - 'SHLVL': '1', 21:48:17 INFO - 'TERM': 'linux', 21:48:17 INFO - 'TMOUT': '86400', 21:48:17 INFO - 'USER': 'cltbld', 21:48:17 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', 21:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:17 INFO - '_': '/tools/buildbot/bin/python'} 21:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:48:17 INFO - Running setup.py (path:/tmp/pip-VgRyP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:48:17 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)) 21:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:48:17 INFO - Running setup.py (path:/tmp/pip-LQtAFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:48:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:48:17 INFO - Running setup.py (path:/tmp/pip-TsuwX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:48:17 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)) 21:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:48:17 INFO - Running setup.py (path:/tmp/pip-z_L6vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:48:17 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)) 21:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:48:17 INFO - Running setup.py (path:/tmp/pip-5HZVll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:48:17 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)) 21:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:48:17 INFO - Running setup.py (path:/tmp/pip-DjYgIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:48:18 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)) 21:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:48:18 INFO - Running setup.py (path:/tmp/pip-gH9f2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:48:18 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)) 21:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:48:18 INFO - Running setup.py (path:/tmp/pip-YF8D3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:48:18 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)) 21:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:48:18 INFO - Running setup.py (path:/tmp/pip-jG9f8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:48:18 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)) 21:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:48:18 INFO - Running setup.py (path:/tmp/pip-Hq1IJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:48:18 INFO - Running setup.py (path:/tmp/pip-e9lJRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:48:18 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)) 21:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:48:18 INFO - Running setup.py (path:/tmp/pip-2dQRqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:48:18 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)) 21:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:48:18 INFO - Running setup.py (path:/tmp/pip-sHcccN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:48:18 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)) 21:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:48:18 INFO - Running setup.py (path:/tmp/pip-kBHsPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:18 INFO - Running setup.py (path:/tmp/pip-0JLBBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:18 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)) 21:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:48:18 INFO - Running setup.py (path:/tmp/pip-Ci7mUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 21:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:48:18 INFO - Running setup.py (path:/tmp/pip-0yaBgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:48:18 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)) 21:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:48:18 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)) 21:48:18 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)) 21:48:18 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)) 21:48:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:48: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 21:48: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 21:48: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 21:48: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 21:48: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 21:48: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 21:48:20 INFO - Downloading blessings-1.6.tar.gz 21:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:48:20 INFO - Installing collected packages: blessings 21:48:20 INFO - Running setup.py install for blessings 21:48:20 INFO - Successfully installed blessings 21:48:21 INFO - Cleaning up... 21:48:21 INFO - Return code: 0 21:48:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:48:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:48:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:48:21 INFO - Reading from file tmpfile_stdout 21:48:21 INFO - Current package versions: 21:48:21 INFO - argparse == 1.2.1 21:48:21 INFO - blessings == 1.6 21:48:21 INFO - blobuploader == 1.2.4 21:48:21 INFO - docopt == 0.6.1 21:48:21 INFO - functools32 == 3.2.3-2 21:48:21 INFO - jsonschema == 2.5.1 21:48:21 INFO - manifestparser == 1.1 21:48:21 INFO - mozInstall == 1.12 21:48:21 INFO - mozcrash == 1.0 21:48:21 INFO - mozdebug == 0.1 21:48:21 INFO - mozdevice == 0.48 21:48:21 INFO - mozfile == 1.2 21:48:21 INFO - mozhttpd == 0.7 21:48:21 INFO - mozinfo == 0.9 21:48:21 INFO - mozleak == 0.1 21:48:21 INFO - mozlog == 3.4 21:48:21 INFO - moznetwork == 0.27 21:48:21 INFO - mozprocess == 0.23 21:48:21 INFO - mozprofile == 0.28 21:48:21 INFO - mozrunner == 6.13 21:48:21 INFO - mozscreenshot == 0.1 21:48:21 INFO - mozsystemmonitor == 0.3 21:48:21 INFO - moztest == 0.8 21:48:21 INFO - mozversion == 1.4 21:48:21 INFO - psutil == 3.1.1 21:48:21 INFO - requests == 1.2.3 21:48:21 INFO - wsgiref == 0.1.2 21:48:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48: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')]} 21:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48: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')]} 21:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:21 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 0x7ffbc7e27f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x256fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256cf60>, '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 0x256dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x256e1d0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', '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 21:48:21 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 21:48:21 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 21:48:21 INFO - Using env: {'DISPLAY': ':0', 21:48:21 INFO - 'HOME': '/home/cltbld', 21:48:21 INFO - 'LANG': 'en_US.UTF-8', 21:48:21 INFO - 'LANGUAGE': 'en_US:en', 21:48:21 INFO - 'LOGNAME': 'cltbld', 21:48:21 INFO - 'MAIL': '/var/mail/cltbld', 21:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:21 INFO - 'MOZ_NO_REMOTE': '1', 21:48:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:21 INFO - 'NO_EM_RESTART': '1', 21:48:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:21 INFO - 'PWD': '/builds/slave/test', 21:48:21 INFO - 'SHELL': '/bin/bash', 21:48:21 INFO - 'SHLVL': '1', 21:48:21 INFO - 'TERM': 'linux', 21:48:21 INFO - 'TMOUT': '86400', 21:48:21 INFO - 'USER': 'cltbld', 21:48:21 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', 21:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:21 INFO - '_': '/tools/buildbot/bin/python'} 21:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:21 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)) 21:48:21 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)) 21:48:21 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)) 21:48:21 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)) 21:48:21 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)) 21:48:21 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)) 21:48:21 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)) 21:48:21 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)) 21:48:21 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)) 21:48:21 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)) 21:48:21 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)) 21:48:21 INFO - Cleaning up... 21:48:21 INFO - Return code: 0 21:48:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:48: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')]} 21:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48: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')]} 21:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:21 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 0x7ffbc7e27f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x256fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256cf60>, '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 0x256dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x256e1d0>, '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': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', '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 21:48:21 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 21:48:21 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 21:48:21 INFO - Using env: {'DISPLAY': ':0', 21:48:21 INFO - 'HOME': '/home/cltbld', 21:48:21 INFO - 'LANG': 'en_US.UTF-8', 21:48:21 INFO - 'LANGUAGE': 'en_US:en', 21:48:21 INFO - 'LOGNAME': 'cltbld', 21:48:21 INFO - 'MAIL': '/var/mail/cltbld', 21:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:21 INFO - 'MOZ_NO_REMOTE': '1', 21:48:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:21 INFO - 'NO_EM_RESTART': '1', 21:48:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:21 INFO - 'PWD': '/builds/slave/test', 21:48:21 INFO - 'SHELL': '/bin/bash', 21:48:21 INFO - 'SHLVL': '1', 21:48:21 INFO - 'TERM': 'linux', 21:48:21 INFO - 'TMOUT': '86400', 21:48:21 INFO - 'USER': 'cltbld', 21:48:21 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', 21:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:21 INFO - '_': '/tools/buildbot/bin/python'} 21:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:48:21 INFO - Cleaning up... 21:48:21 INFO - Return code: 0 21:48:21 INFO - Running post-action listener: _resource_record_post_action 21:48:21 INFO - Running post-action listener: _start_resource_monitoring 21:48:21 INFO - Starting resource monitoring. 21:48:21 INFO - [mozharness: 2017-01-24 05:48:21.797194Z] Finished create-virtualenv step (success) 21:48:21 INFO - [mozharness: 2017-01-24 05:48:21.797789Z] Running install step. 21:48:21 INFO - Running pre-action listener: _resource_record_pre_action 21:48:21 INFO - Running main action method: install 21:48:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:48:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:48:22 INFO - Reading from file tmpfile_stdout 21:48:22 INFO - Detecting whether we're running mozinstall >=1.0... 21:48:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:48:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:48:22 INFO - Reading from file tmpfile_stdout 21:48:22 INFO - Output received: 21:48:22 INFO - Usage: mozinstall [options] installer 21:48:22 INFO - Options: 21:48:22 INFO - -h, --help show this help message and exit 21:48:22 INFO - -d DEST, --destination=DEST 21:48:22 INFO - Directory to install application into. [default: 21:48:22 INFO - "/builds/slave/test"] 21:48:22 INFO - --app=APP Application being installed. [default: firefox] 21:48:22 INFO - mkdir: /builds/slave/test/build/application 21:48:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:48:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:48:35 INFO - Reading from file tmpfile_stdout 21:48:35 INFO - Output received: 21:48:35 INFO - /builds/slave/test/build/application/firefox/firefox 21:48:35 INFO - Running post-action listener: _resource_record_post_action 21:48:35 INFO - [mozharness: 2017-01-24 05:48:35.701877Z] Finished install step (success) 21:48:35 INFO - [mozharness: 2017-01-24 05:48:35.702123Z] Running run-tests step. 21:48:35 INFO - Running pre-action listener: _resource_record_pre_action 21:48:35 INFO - Running main action method: run_tests 21:48:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:48:35 INFO - Python 2.7.3 21:48:35 INFO - Return code: 0 21:48:35 INFO - grabbing minidump binary from tooltool 21:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:35 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 0x256cf60>, '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 0x256dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x256e1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:48:35 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 21:48:35 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 21:48:35 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 21:48:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:48:35 INFO - Return code: 0 21:48:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:48:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:48:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:48:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:48:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:48:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 21:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 21:48:35 INFO - Using env: {'DISPLAY': ':0', 21:48:35 INFO - 'HOME': '/home/cltbld', 21:48:35 INFO - 'LANG': 'en_US.UTF-8', 21:48:35 INFO - 'LANGUAGE': 'en_US:en', 21:48:35 INFO - 'LOGNAME': 'cltbld', 21:48:35 INFO - 'MAIL': '/var/mail/cltbld', 21:48:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:48:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:35 INFO - 'MOZ_NO_REMOTE': '1', 21:48:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:48:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:35 INFO - 'NO_EM_RESTART': '1', 21:48:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:35 INFO - 'PWD': '/builds/slave/test', 21:48:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:48:35 INFO - 'SHELL': '/bin/bash', 21:48:35 INFO - 'SHLVL': '1', 21:48:35 INFO - 'TERM': 'linux', 21:48:35 INFO - 'TMOUT': '86400', 21:48:35 INFO - 'USER': 'cltbld', 21:48:35 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983', 21:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:35 INFO - '_': '/tools/buildbot/bin/python'} 21:48:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 21:48:35 INFO - mozversion application_buildid: 20170123195043 21:48:35 INFO - mozversion application_changeset: 7e83a181036b71708716f64d644f10b38a479e69 21:48:35 INFO - mozversion application_display_name: Firefox 21:48:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:48:35 INFO - mozversion application_name: Firefox 21:48:35 INFO - mozversion application_remotingname: firefox 21:48:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 21:48:35 INFO - mozversion application_vendor: Mozilla 21:48:35 INFO - mozversion application_version: 52.0 21:48:35 INFO - mozversion platform_buildid: 20170123195043 21:48:35 INFO - mozversion platform_changeset: 7e83a181036b71708716f64d644f10b38a479e69 21:48:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 21:48:35 INFO - mozversion platform_version: 52.0 21:48:35 INFO - using testdate: 1485236915 21:48:35 INFO - actual date: 1485236915 21:48:35 INFO - starting webserver on 'localhost:38397' 21:48:35 INFO - SUITE-START | Running 2 tests 21:48:35 INFO - TEST-START | dromaeo_css 21:48:35 INFO - operating with platform_type : linux_ 21:48:35 INFO - Initialising browser for dromaeo_css test... 21:48:35 INFO - TEST-INFO | started process 25761 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDhOJes/profile http://localhost:38397/getInfo.html) 21:48:40 INFO - PROCESS | 25761 | __metrics Screen width/height:1600/1200 21:48:40 INFO - PROCESS | 25761 | colorDepth:24 21:48:40 INFO - PROCESS | 25761 | Browser inner width/height: 1024/697 21:48:40 INFO - PROCESS | 25761 | __metrics 21:48:40 INFO - PROCESS | 25761 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:48:40 INFO - PROCESS | 25761 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:48:41 INFO - PROCESS | 25761 | console.error: PushService: 21:48:41 INFO - PROCESS | 25761 | stateChangeProcessEnqueue: Error transitioning state 21:48:41 INFO - PROCESS | 25761 | UnknownError 21:48:41 INFO - TEST-INFO | 25761: exit 0 21:48:41 INFO - Browser initialized. 21:48:41 INFO - Running cycle 1/1 for dromaeo_css test... 21:48:41 INFO - TEST-INFO | started process 25856 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDhOJes/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:48:53 INFO - PROCESS | 25856 | RSS: Main: 144437248 21:48:53 INFO - PROCESS | 25856 | 21:51:55 INFO - PROCESS | 25856 | Cycle 1(1): loaded http://localhost:38397/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38397/tests/dromaeo/cssquery-ext.html) 21:51:56 INFO - PROCESS | 25856 | RSS: Main: 251228160 21:51:56 INFO - PROCESS | 25856 | 21:54:58 INFO - PROCESS | 25856 | Cycle 1(1): loaded http://localhost:38397/tests/dromaeo/cssquery-ext.html (next: http://localhost:38397/tests/dromaeo/cssquery-jquery.html) 21:54:59 INFO - PROCESS | 25856 | RSS: Main: 227262464 21:54:59 INFO - PROCESS | 25856 | 21:58:00 INFO - PROCESS | 25856 | Cycle 1(1): loaded http://localhost:38397/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38397/tests/dromaeo/cssquery-mootools.html) 21:58:01 INFO - PROCESS | 25856 | RSS: Main: 179089408 21:58:01 INFO - PROCESS | 25856 | 22:01:03 INFO - PROCESS | 25856 | Cycle 1(1): loaded http://localhost:38397/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38397/tests/dromaeo/cssquery-prototype.html) 22:01:04 INFO - PROCESS | 25856 | RSS: Main: 173363200 22:01:04 INFO - PROCESS | 25856 | 22:04:06 INFO - PROCESS | 25856 | Cycle 1(1): loaded http://localhost:38397/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38397/tests/dromaeo/cssquery-yui.html) 22:04:07 INFO - PROCESS | 25856 | RSS: Main: 176828416 22:04:07 INFO - PROCESS | 25856 | 22:07:09 INFO - PROCESS | 25856 | Cycle 1(1): loaded http://localhost:38397/tests/dromaeo/cssquery-yui.html (next: http://localhost:38397/tests/dromaeo/cssquery-dojo.html) 22:07:09 INFO - PROCESS | 25856 | RSS: Main: 262737920 22:07:09 INFO - PROCESS | 25856 | 22:07:09 INFO - PROCESS | 25856 | __start_tp_report 22:07:09 INFO - PROCESS | 25856 | _x_x_mozilla_page_load 22:07:09 INFO - PROCESS | 25856 | _x_x_mozilla_page_load_details 22:07:09 INFO - PROCESS | 25856 | |i|pagename|runs| 22:07:09 INFO - PROCESS | 25856 | |0;dojo.html;3227.903162905113;3235.1588586967387;3254.853531591078;3284.1297056280073;3306.3222039481907;5622.044252477079;5690.975624387608;5698.145278208271;5700.087985922253;5709.514691251248;5708.600397972144;5789.0448076067505;5790.942090579093;5791.986402379577;5800.970995145019;5170.793168273274;5210.1403268460845;5224.791008359661;5259.395169555492;5293.788248470057;5076.517730815585;5090.720010399426;5290.92063619046;5299.735013249322;5333.333333333333;5097.643164978453;5148.176291793314;5161.638685292031;5181.844544663647;5209.791608335681;5179.093658609747;5265.210218467241;5265.473452654742;5283.101872681654;5322.5209731124;4875.780589873429;4978.850634480971;5155.639105262633;5184.974075129638;5238.633295669305;5493.505584497374;5606.803761868315;5633.211350410946;5637.182608521783;5659.773609055633;6079.148919151322;6119.326874043852;6123.2039834821635;6126.9387306127255;6146.200993870768;5928.347881733006;5997.160397544348;6129.754809807602;6143.232095988002;6147.200863887666;5619.943800561983;5724.570657200685;5742.138679198112;5790.826275211751;5829.038208695518;6281;6305.211848518935;6318.4313411793155;6335.588186767845;6339.587926784744;5805.216295800013;5851.707414629252;6068.67526354106;6072.180255665517;6089.299730530957;6013.0980352946535;6057.757689692451;6060.757569697251;6122.693386293493;6155.353687862799;6117.096718007379;6121.005657821351;6126.864481135799;6127.294493216227;6134.7179259096165;5822.388649191774;5888.440598143169;6162.907556386658;6171.691415429211;6177.2587289525545;4604.263317869192;4641.071785642817;4651.139539185261;4660.836870709509;4673.532646735299;4156.896077598084;4163.5836416358725;4168.18720349535;4173.207090652766;4195.769232692171;5788.797392091256;5804.564657650693;5818.0981947798155;5821.79623713168;5833.095870140135;5903;5959.374265702038;5979.372165922602;6031.2159419276095;6076.5442591805795;5809.070548712186;5810.88378232433;5812.796552120655;5815.709214539256;5831.7667293307795;5192.948070519322;5208.166693329049;5208.765605547759;5211.452797456288;5236.345456817898;6121.574916084878;6145.293291271471;6208.782692605828;6220.533459990609;6297.281942409421;6075.969620152047;6081.087836824512;6082.908756368746;6101.938980610137;6122.785702500391;6262.68686565679;6274.749010039547;6279.215098112736;6307.590006649553;6333.904991425225;5779.2198053263955;5829.737661805314;5899.882002360015;5966.612170209096;5974.133750606051;6100.1154832548145;6155.722992465439;6161.907571386343;6173.506119510518;6185.597936134137;6005.609635373687;6010.909836352545;6013.7293821779;6013.9699301503215;6035.788747393995;5694.857628559319;5816.651000939957;5971.91042134377;5977.462028417463;5982.940170598238;5897.469227769521;5972.223610930725;6013.9097913532205;6061.909071363845;6067.514598831995;6102.542309326729;6109.602875813058;6115;6115.021596544531;6117.449429551361;5624.971875140598;5680.488756011813;5711.828645140652;5806.796762113475;5940.584159108994 22:07:09 INFO - PROCESS | 25856 | |1;ext.html;593.7179839576202;627.6516533324007;651.7425616881332;667.2760055339955;669.0365873142671;34498;46073.53926460739;46854.297185542244;46961.29558056632;47107.29339059908;46107;46531.53468465323;46544.53455465444;46559;46632;8484.83030339396;8682.739517814443;8898.822023559542;9045.683401080963;9048.547572621375;10654.733631659232;10917.945410272938;10927.344359338465;11136.665900023012;11154.330740155616;11002.064824489928;11054.944725276362;11063.004329610374;11066.889331106666;11075.667729968114;10841.13270938327;10919.890801091968;10950.835737463944;10952.233343665946;10956;10610.681679549625;10648.41433721145;10670.573177072985;10674.09270212033;10685.893141068567;44385;44917.550824491664;44963.77518112421;44964.55035449669;44976;3607.9098022549383;3667.7799332040163;4305.095929854734;4383.802728877208;4487.9446168536615;33263.33473330544;33380.499292510634;33388.499172512435;34168.82915585431;34615.480767788504;3805.8858234253016;3884.261990221849;3911.9217615647813;3933.272344616255;3971.980140099281;354587;356034;356706.9999999974;357558;357608;63403;63712.681436592524;63790;63817.68091159515;63896;36919.446208306894;37512.81243593819;37534.06164845846;37640.43539346912;66628;15172.089674619558;15196.164210968504;15216.619584510476;15218.239088045553;15243.314050867737;13409.128406653535;13690.52083177084;14102.506412275727;14479.92760036193;14618.707625847423;20472.283470078477;20657.483562910747;20770.48073798167;20861.165553378;20864.47838804042;7514.924850751532;7608.543487390774;7682.462227644123;7799.5320280783335;7827.686892524235;1000.4747507558444;1065.5577935156823;1107.341132026443;1133.2237417369129;1153.7461758413135;33259;33895.83052084724;33939.490907636406;33951.4907276391;34046.48930266048;1049.8687664041995;1127.8195488721929;1169.3042639629407;1182.0897908610493;1195.1036722458157;17851.196696148443;18011.099445027696;18183.363582274822;19006.80993190078;19957.20171193136;492030;493516;494944;497173;498152;63283.367166328666;63617.68191159107;63648.045279320846;63729.68135159387;63790;1321.7819059855208;1393.8257717785277;1403.9157650541001;1409.8237720284965;1454.425501926733;30656;34698.65301346954;34892;35028;35087.82456087703;53096.46903530915;53239;53379;53615.19577206423;53822;395825.04174957884;401579;406220;406395;406598;28014.579781302888;28593;29907.700922991364;30508.084757457313;30586.541201881544;48970.510294896594;49644;49685.25472117993;49827.25950072781;50365.24452133148;41848.3722744153;41994.58005419907;42052.36921446242;42419;43582.56417435912;42501;42662.78668606637;43229.783851080545;43307.35038974355;44666.776666116464;42303;42911.78544107259;44215.778921106474;44300.11399771922;44476.55523444724;35998;38500.22999540049;38547.614523855525;38917.02707432337;39016.80491597524;28230.43539129165;28275.434491309647;28397.716022839508;28641.570376443953;28880.85559572189 22:07:09 INFO - PROCESS | 25856 | |2;jquery.html;417.1844044892234;421.1576846307385;423.8749568877181;429.26595521657975;433.3478115436268;18889.527761805944;19944.301949431774;20559.588808223827;20589.691154632674;20726.067326970286;20151.29470468534;20409.183632654676;20424.89787551064;20611.69082463764;20794.480137996557;5604.355499117592;5885.676287804169;5885.705714714269;5889.8822023559715;5891.381404952483;4224.352323890173;5099.71951542665;5782.421757824226;5865.912011319833;6528.510361722866;6460.450861676763;6477.514186436036;6478.093066970628;6478.352164783531;6483.481321494269;6417.647029413403;6467.5472716909835;6579.93420065798;6591.934080659228;6597;5860.853478663025;5877.882442351129;5878.676672782995;5879;5889.911651325191;24351.26946191617;24360.26919192427;24534;24560.877195614084;24755.38111547208;2472.3819045238693;2476.5170791695627;2505.5615267328144;2514.974850251492;2560.6543116679195;15153.924230378887;15259;15337.30982105808;15613.297401616954;16009.91995040029;1922.9326973555858;1985.2753744883157;1992.681171012631;2000.9799902000939;2024.1093918675883;81438.59280703618;82586.58706706489;82839.17160828375;83068;87350;10994;11127.666170014912;11670.299782013106;11721.35532545701;11826.231294965799;16303;16351.918240408722;16353;16378;16396.590085247964;8458.519759042143;8680.34897382699;8818.33862460318;8844.115588441104;8870.778230544289;7564.962175189089;7583.2416758324825;7703.614819259014;7811.882821757678;8220.17798220185;7002.159740831133;7041.718331266795;7075.063848502917;7184.784456466315;7190.9280907191305;1957.8727382720078;2018.8889611071531;2021.7573891133159;2023.6559784836318;2043.9591208175752;943.9150476457014;965.7440778193784;1045.5765415006783;1054.035557464911;1087.9292845964987;19971.900140499205;20395.694064589043;21080.683789743165;21325.68011479829;21357;1012.5645972232011;1016.7204018894863;1067.829147336438;1089.9182561307935;1136.8465257190173;10108.898911010796;12021.639350819489;12138.393080345948;12155.5137794489;12167.817482737766;440709;458707;478338;483865;484185;86046;86192.56903715442;86300;86389.56805215933;87232;1039.594558122333;1059.3537941855461;1091.8962698543626;1111.42704638761;1115.486876037032;17808.287668493635;17858.5535361617;18147.54631134232;18639.534011649816;18759;41500.1699966005;42302.36546451867;42492.78753606212;42559.5744042568;42771.7861410691;54935.72532137474;55165.72417137888;55257;56456.435435645115;57998.7100064497;8460;9133.954330228305;9142.22291105237;9264.073592640681;9422.387544809566;13060.934695326463;13770.586882393543;14015.579532614038;14075.225862577247;14200.28998550089;11824.1131915105;11974.820377694168;12173.634790956285;12180.147389682992;12479.93760031194;11645.068394528247;11738;11851.111166662362;11972.102092342935;12083.214591051905;12107.757844842878;12268.938655306667;12414.193077449936;12445.62663120108;12586.056045796786;8934.553272336487;9114.13415725505;9144.63421463134;9349.48577828226;9379.906200937903;11871.050315974873;11932.403379831147;12147.574834880737;12474.313912734886;12565.874341256836 22:07:09 INFO - PROCESS | 25856 | |3;mootools.html;1268.099649249033;1519.8860085493586;1528.4387377410635;1731.1603872122018;1864.7109697996807;7967.521948683083;8866.069062748415;9124.361294709357;9131.178193962542;9135.725928222148;8962;9037.18665320121;9051.637934482613;9066.818663626724;9070.818583628323;2437.9512409751837;2466.1984854922134;2467.7902378297777;2467.962980555293;2471.9629205561932;2407.8916448759846;2474.480359124586;2488.850668959859;2496.8002559795264;2503.198976327576;2441.560519106569;2445.9633105503344;2452.619843924195;2457.45935894103;2471.369800700828;2303.516261585069;2341.9063237470564;2348.8942997565064;2385.1175065225802;2426.344886880532;2391.988040059806;2422.6608274841537;2428.514297140579;2429.829911906167;2432.6959130108735;8879.200871921557;8942.821143577157;8970.686025989124;9110.635574577009;9231.584578693975;5583.162525621149;5728.42715728428;5742.081266997302;5751.568632352591;5755.971220143914;3345.2305969626877;3415.3681568909833;3418.7094097001786;3446.8104254266004;3692.058525076115;1068.417712346765;1076.0508932218777;1102.189890430541;1106.80630889594;1107.6510899066768;12220.205686630341;12329.260244385276;12367.381630918419;12410.875891241152;12411.627651170389;4673.252279635242;4752.239641657318;4766.453669377403;4782.258749893772;4895.534924182197;9979.451130187765;10004.54979525923;10009.299349045643;10019;10027.79944401108;5467.72661366938;5473.370562385297;5487.75305111271;5488.341399032141;5501.807436739695;4350.236457333983;4602.309653551994;4686.484486706459;4710.034442939189;5087.542121209109;3957.1887763008513;4044.312466880637;4160.2303573838335;4171.249175148441;4182.519010313792;1234.2902830872106;1253.423425224407;1255.8681338459512;1262.9179103358251;1272.9490820367082;905.3391024551983;970.3013830042358;1006.1146191351564;1008.7175590834578;1051.2115913065202;6192.5974811638;6207.534434917309;6293.622382657055;6301.275353334425;6478.449331806849;469.4249544308195;477.81365267545686;485.36902027364283;497.0407114269495;503.692747424071;4274.380214868889;4364.563543645673;4364.694471387036;4395.868123956287;4409.845655402046;10994.120470362352;11358.432078396047;11395.601153959571;11442;11484.827727584094;11192.272502287326;11200.495977681025;11284.322940623424;11345.319280843176;11416.143789215675;8667.913320866837;8760.386772925835;8767;8768.912310876936;8780.824383512294;5971.641701497924;6048.60684055535;6126.203593532812;6152.354002829638;6237.376262373908;10039.899601003897;10311.639092632022;10363.585456581954;10437.373757574569;10448.791024179322;9516.282167906806;9529.57116929726;10496.05535501778;10561.260711750103;10625.36247825102;7783.688652453839;7804.570748608881;8150.470219435718;8227.670893164206;8228.382871284797;8756.387052906235;8972.37193396456;9048.864267035868;9052.81894362096;9061.818763624822;7818.452708310364;7830.725924592612;7883.448158628841;7973.920260797549;8083.8383232333845;7891.84216315682;7901.486403383762;7977.960110199412;8035.357171426155;8170.0604430488875;7416.7404140854815;7471.514351567131;7544.207858174813;7559.395248380007;7572.053493313336;5337.733113344395;5390.703511306916;5449.782008719607;5454.4545545445135;5531.391546929915;5504.587155963239;5594.608377413542;5644.3508996465935;5653.349864765604;5717.742701578522 22:07:09 INFO - PROCESS | 25856 | |4;prototype.html;349.8076058168007;356.89828398906303;359.8776416018553;362.71889285803496;368.0798004987531;5354.330708661418;5365.812196573121;5407.810281738011;5414.702191379472;5415.756290966906;5313.521783039526;5335.493128152829;5343.866403339918;5355;5385.415000799619;3758.5113935188524;4019.4372787809657;4199.538050814408;4242.703010789246;4255.63827074699;4261.339492378686;4263.722858014219;4264.701470897038;4268.231718290706;4269.6370808481315;4224.471941007374;4237.194932962727;4237.4279472271155;4237.449131612886;4240.579405899012;4155.667546596265;4208.452901122834;4226.450561426995;4247.447831781864;4257.616814486695;3775.7734535927657;3789.9767062892865;3804.144067584799;3804.3912973924316;3821.6942644588366;5243.318368612094;5246.554042906358;5255.211718242256;5264.184051472028;5271.630985830993;4820.349252850855;4850.490698476679;4890.168671325883;4900.558949694509;4924;5577.8605534861545;5605.626621477764;5613.270274864241;5616.157576363537;5621.381648018714;4192.11965487248;4200.096979149467;4201.537830838605;4205.411242426063;4226.830926762926;5406.216098665672;5429.828068772483;5821.88356232873;5848.795292164754;5874.9118763218585;4238.554951730086;4251.851185208503;4258.68059895509;4267.701260911706;4272.401863739017;4320.848770293088;4324.675649326313;4355.041890784086;4360.738355698605;4375.190589740844;4585.862424127282;4889.579658069279;4895.265710143507;4926.261060840902;4926.9306891105825;5635.408282130399;5645.125005624135;5653.321601407775;5666.376698563155;5704.657720536781;1411.964700882486;1414.9717005660034;1427.2444892781937;1437.7563407652515;1442.5383877159418;944.8346539355723;994.6469003503797;998.2662742883974;998.6754304850807;1009.1371877045168;5241.50205730455;5279.3370088422025;5303.893922121536;5306.363236411578;5309.389420216725;5569.721513924287;5571.9442805572235;5574.689947862249;5622.0484245771295;5639.407862174495;5025.221090730943;5233.581313494911;5269.182132164397;5297.973510132425;5305.867353316198;2929.399473108008;2949.0656796487206;2969.5248760198265;2989.8505074746176;2998.29097414476;5749.655020698771;5756.33802112754;5761.8559536011935;5800.419958004166;5805.912911306334;5599.468050535193;5921.851953701192;5924.437178468039;5939.406059394026;5952.970235148883;6000.549958753111;6039.335673075958;6048.334683184846;6059.48494377974;6064.332923378513;5554.527865131509;5799.3040835100055;5802.738876750472;5820.417958204146;5834.679092649945;5726.370099289158;5752.9712351437975;5774.9133762994425;5783.248177736868;5796.536277097906;5784.21913041734;5935.7032148391545;5942;5962.642241465526;6016.969915150396;4091.8772436826944;4202.41166236721;4232.814811852686;4243.6180743731975;4247.088810035435;5659.377468478382;5796.1305804129715;5810.825675229581;5821.09772985188;5821.243238378984;5642.379338272869;5645.604807663424;5654.971725141348;5663.716814159358;5678.716064196857;5570.359408668055;5581.995240856522;5600.719964001865;5614.803481878114;5651.180578816131;5407.567394608343;5540.7783688652;5562.72186390687;5564.777408903598;5589;5596.1605759136455;5598.748056337556;5608.775648973995;5610;5646.88706225865;5480.424555421623;5486.533644640251;5506.229127922014;5517.862053448696;5539.473749993744 22:07:09 INFO - PROCESS | 25856 | |5;yui.html;741.6291854072964;781.5467029123106;835.0480452284393;853.8932633420823;876.2726936642586;15050.924745376271;15225.390984360625;15471.303791329388;15679.45121920733;16617.16914154292;16370.754438683429;16738;16814.747778783298;17013.82986170138;17055.658886822257;738.9815254618651;873.7378786364097;890.3856339126015;890.8708237305586;891.61660485991;759.0549765541908;764.8164440534288;765.0589774577273;850.0819115355432;867.8134201146752;928.517171071046;932.5290728182258;933.0948979489884;933.3886304470583;934.728928610702;923.9214666753334;924.191332583989;924.3298608508777;924.8104138651561;924.8982611912684;682.205230905994;682.2358957967059;682.4881338995754;683.1597135523334;683.6547543490506;15384.538463846102;15437.228138593026;15993.28030238642;16006;16008.359665613361;14101.78847317291;14596.562103136923;14832.851671483255;15339.233038348038;15392.769108463383;12540.24758514492;12609.558665446666;12758.170718903242;12809;12975.221486710732;3801.752886062397;3833.310004199243;3882.786446745428;3924.2347742190286;4003.399490076512;66267.66866165686;68127.31872681259;68451;69564.6521767393;69859;10830.620928267474;10861.67414977552;11250;11358.148138889615;11712.70718232034;20895.8955205223;20923.058462368925;20947.581048379252;21026.579468410546;21690.89154554217;7714.498557593738;8475.788105297417;8766.912330876738;8829.337799664921;8999.685011024583;4630.9536904631195;4673.929891051637;4683.882902927454;4707.8587642370785;4881.414230292387;3726.4969229153717;3851.30676478231;3857.633524815138;3860.5946375630715;3863.03424143964;673.865226954611;684.1106561470069;689.8103021669081;689.8689249042666;700.3346820520526;731.0861423220974;732.3555271361168;750.8047907543969;752.3792870881545;759.3735168486021;9380.95309523448;9479.24166066713;9747.610095596097;9972.501374931224;10006;543.8368489453148;553.5848113914564;568.8728276355018;570.4295704295704;572.4475880775072;4860.441049279308;4914.950850491521;4954.653174277838;4959.20652695567;5021.447640759514;57789.71105144447;57966.420335796945;60395.69802150961;89831;90192;38018.80990595029;75770.24229757742;77823.61088194523;78745;80004.19995799968;46391.536084639396;72485.2751472489;73559.63220183972;74494.6275268631;75612.24387756162;9326.393784403992;9373.953130234306;9564.66523671668;9632.95183524078;9704.6118155273;48647.27029094637;49160.26259606037;49365.75317123535;50310.748446259;51659;62024.37975620186;63181.68409158109;67749;68809.31190688029;69231.65384173047;12483.812742809047;12543.3101179436;12673.93663031679;12886.548970785978;13099.541516046893;13351.732965340832;13807;14786.852131478978;14842.703145937237;15357.001794883743;12952.352382380655;13083.607491775261;13201.669958250737;13543.119697219649;13584.592462226148;13536.932315338361;13757.724845502833;13880.652983675489;13904.51334203298;14324.14055156694;13448.663783405493;13642.658933526742;13897.444102235797;14095.718085638435;14297.142171469744;10916.781664366827;11019.559217631206;11166.720831979266;11272.492737826653;11408.372539510407;10023.799524009624;10293.176545876162;11072.889271107186;11111.333320000826;11230.55077796879 22:07:09 INFO - PROCESS | 25856 | __end_tp_report 22:07:09 INFO - PROCESS | 25856 | __start_cc_report 22:07:09 INFO - PROCESS | 25856 | _x_x_mozilla_cycle_collect,1054 22:07:09 INFO - PROCESS | 25856 | __end_cc_report 22:07:09 INFO - PROCESS | 25856 | __startTimestamp1485238029813__endTimestamp 22:07:09 INFO - PROCESS | 25856 | 22:07:09 INFO - PROCESS | 25856 | ------- Summary: start ------- 22:07:09 INFO - PROCESS | 25856 | Number of tests: 6 22:07:09 INFO - PROCESS | 25856 | 22:07:09 INFO - PROCESS | 25856 | [#0] dojo.html Cycles:165 Average:5660.96 Median:5829.74 stddev:647.98 (11.1%) stddev-sans-first:621.22 22:07:09 INFO - PROCESS | 25856 | Values: 3227.9 3235.2 3254.9 3284.1 3306.3 5622.0 5691.0 5698.1 5700.1 5709.5 5708.6 5789.0 5790.9 5792.0 5801.0 5170.8 5210.1 5224.8 5259.4 5293.8 5076.5 5090.7 5290.9 5299.7 5333.3 5097.6 5148.2 5161.6 5181.8 5209.8 5179.1 5265.2 5265.5 5283.1 5322.5 4875.8 4978.9 5155.6 5185.0 5238.6 5493.5 5606.8 5633.2 5637.2 5659.8 6079.1 6119.3 6123.2 6126.9 6146.2 5928.3 5997.2 6129.8 6143.2 6147.2 5619.9 5724.6 5742.1 5790.8 5829.0 6281.0 6305.2 6318.4 6335.6 6339.6 5805.2 5851.7 6068.7 6072.2 6089.3 6013.1 6057.8 6060.8 6122.7 6155.4 6117.1 6121.0 6126.9 6127.3 6134.7 5822.4 5888.4 6162.9 6171.7 6177.3 4604.3 4641.1 4651.1 4660.8 4673.5 4156.9 4163.6 4168.2 4173.2 4195.8 5788.8 5804.6 5818.1 5821.8 5833.1 5903.0 5959.4 5979.4 6031.2 6076.5 5809.1 5810.9 5812.8 5815.7 5831.8 5192.9 5208.2 5208.8 5211.5 5236.3 6121.6 6145.3 6208.8 6220.5 6297.3 6076.0 6081.1 6082.9 6101.9 6122.8 6262.7 6274.7 6279.2 6307.6 6333.9 5779.2 5829.7 5899.9 5966.6 5974.1 6100.1 6155.7 6161.9 6173.5 6185.6 6005.6 6010.9 6013.7 6014.0 6035.8 5694.9 5816.7 5971.9 5977.5 5982.9 5897.5 5972.2 6013.9 6061.9 6067.5 6102.5 6109.6 6115.0 6115.0 6117.4 5625.0 5680.5 5711.8 5806.8 5940.6 22:07:09 INFO - PROCESS | 25856 | 22:07:09 INFO - PROCESS | 25856 | [#1] ext.html Cycles:180 Average:59234.47 Median:37523.44 stddev:111399.95 (296.9%) stddev-sans-first:111625.44 22:07:09 INFO - PROCESS | 25856 | Values: 593.7 627.7 651.7 667.3 669.0 34498.0 46073.5 46854.3 46961.3 47107.3 46107.0 46531.5 46544.5 46559.0 46632.0 8484.8 8682.7 8898.8 9045.7 9048.5 10654.7 10917.9 10927.3 11136.7 11154.3 11002.1 11054.9 11063.0 11066.9 11075.7 10841.1 10919.9 10950.8 10952.2 10956.0 10610.7 10648.4 10670.6 10674.1 10685.9 44385.0 44917.6 44963.8 44964.6 44976.0 3607.9 3667.8 4305.1 4383.8 4487.9 33263.3 33380.5 33388.5 34168.8 34615.5 3805.9 3884.3 3911.9 3933.3 3972.0 354587.0 356034.0 356707.0 357558.0 357608.0 63403.0 63712.7 63790.0 63817.7 63896.0 36919.4 37512.8 37534.1 37640.4 66628.0 15172.1 15196.2 15216.6 15218.2 15243.3 13409.1 13690.5 14102.5 14479.9 14618.7 20472.3 20657.5 20770.5 20861.2 20864.5 7514.9 7608.5 7682.5 7799.5 7827.7 1000.5 1065.6 1107.3 1133.2 1153.7 33259.0 33895.8 33939.5 33951.5 34046.5 1049.9 1127.8 1169.3 1182.1 1195.1 17851.2 18011.1 18183.4 19006.8 19957.2 492030.0 493516.0 494944.0 497173.0 498152.0 63283.4 63617.7 63648.0 63729.7 63790.0 1321.8 1393.8 1403.9 1409.8 1454.4 30656.0 34698.7 34892.0 35028.0 35087.8 53096.5 53239.0 53379.0 53615.2 53822.0 395825.0 401579.0 406220.0 406395.0 406598.0 28014.6 28593.0 29907.7 30508.1 30586.5 48970.5 49644.0 49685.3 49827.3 50365.2 41848.4 41994.6 42052.4 42419.0 43582.6 42501.0 42662.8 43229.8 43307.4 44666.8 42303.0 42911.8 44215.8 44300.1 44476.6 35998.0 38500.2 38547.6 38917.0 39016.8 28230.4 28275.4 28397.7 28641.6 28880.9 22:07:09 INFO - PROCESS | 25856 | 22:07:09 INFO - PROCESS | 25856 | [#2] jquery.html Cycles:180 Average:28919.99 Median:24547.44 stddev:77308.64 (314.9%) stddev-sans-first:77495.89 22:07:09 INFO - PROCESS | 25856 | Values: 417.2 421.2 423.9 429.3 433.3 18889.5 19944.3 20559.6 20589.7 20726.1 20151.3 20409.2 20424.9 20611.7 20794.5 5604.4 5885.7 5885.7 5889.9 5891.4 4224.4 5099.7 5782.4 5865.9 6528.5 6460.5 6477.5 6478.1 6478.4 6483.5 6417.6 6467.5 6579.9 6591.9 6597.0 5860.9 5877.9 5878.7 5879.0 5889.9 24351.3 24360.3 24534.0 24560.9 24755.4 2472.4 2476.5 2505.6 2515.0 2560.7 15153.9 15259.0 15337.3 15613.3 16009.9 1922.9 1985.3 1992.7 2001.0 2024.1 81438.6 82586.6 82839.2 83068.0 87350.0 10994.0 11127.7 11670.3 11721.4 11826.2 16303.0 16351.9 16353.0 16378.0 16396.6 8458.5 8680.3 8818.3 8844.1 8870.8 7565.0 7583.2 7703.6 7811.9 8220.2 7002.2 7041.7 7075.1 7184.8 7190.9 1957.9 2018.9 2021.8 2023.7 2044.0 943.9 965.7 1045.6 1054.0 1087.9 19971.9 20395.7 21080.7 21325.7 21357.0 1012.6 1016.7 1067.8 1089.9 1136.8 10108.9 12021.6 12138.4 12155.5 12167.8 440709.0 458707.0 478338.0 483865.0 484185.0 86046.0 86192.6 86300.0 86389.6 87232.0 1039.6 1059.4 1091.9 1111.4 1115.5 17808.3 17858.6 18147.5 18639.5 18759.0 41500.2 42302.4 42492.8 42559.6 42771.8 54935.7 55165.7 55257.0 56456.4 57998.7 8460.0 9134.0 9142.2 9264.1 9422.4 13060.9 13770.6 14015.6 14075.2 14200.3 11824.1 11974.8 12173.6 12180.1 12479.9 11645.1 11738.0 11851.1 11972.1 12083.2 12107.8 12268.9 12414.2 12445.6 12586.1 8934.6 9114.1 9144.6 9349.5 9379.9 11871.1 11932.4 12147.6 12474.3 12565.9 22:07:09 INFO - PROCESS | 25856 | 22:07:09 INFO - PROCESS | 25856 | [#3] mootools.html Cycles:180 Average:5976.91 Median:4731.14 stddev:3377.83 (71.4%) stddev-sans-first:3368.77 22:07:09 INFO - PROCESS | 25856 | Values: 1268.1 1519.9 1528.4 1731.2 1864.7 7967.5 8866.1 9124.4 9131.2 9135.7 8962.0 9037.2 9051.6 9066.8 9070.8 2438.0 2466.2 2467.8 2468.0 2472.0 2407.9 2474.5 2488.9 2496.8 2503.2 2441.6 2446.0 2452.6 2457.5 2471.4 2303.5 2341.9 2348.9 2385.1 2426.3 2392.0 2422.7 2428.5 2429.8 2432.7 8879.2 8942.8 8970.7 9110.6 9231.6 5583.2 5728.4 5742.1 5751.6 5756.0 3345.2 3415.4 3418.7 3446.8 3692.1 1068.4 1076.1 1102.2 1106.8 1107.7 12220.2 12329.3 12367.4 12410.9 12411.6 4673.3 4752.2 4766.5 4782.3 4895.5 9979.5 10004.5 10009.3 10019.0 10027.8 5467.7 5473.4 5487.8 5488.3 5501.8 4350.2 4602.3 4686.5 4710.0 5087.5 3957.2 4044.3 4160.2 4171.2 4182.5 1234.3 1253.4 1255.9 1262.9 1272.9 905.3 970.3 1006.1 1008.7 1051.2 6192.6 6207.5 6293.6 6301.3 6478.4 469.4 477.8 485.4 497.0 503.7 4274.4 4364.6 4364.7 4395.9 4409.8 10994.1 11358.4 11395.6 11442.0 11484.8 11192.3 11200.5 11284.3 11345.3 11416.1 8667.9 8760.4 8767.0 8768.9 8780.8 5971.6 6048.6 6126.2 6152.4 6237.4 10039.9 10311.6 10363.6 10437.4 10448.8 9516.3 9529.6 10496.1 10561.3 10625.4 7783.7 7804.6 8150.5 8227.7 8228.4 8756.4 8972.4 9048.9 9052.8 9061.8 7818.5 7830.7 7883.4 7973.9 8083.8 7891.8 7901.5 7978.0 8035.4 8170.1 7416.7 7471.5 7544.2 7559.4 7572.1 5337.7 5390.7 5449.8 5454.5 5531.4 5504.6 5594.6 5644.4 5653.3 5717.7 22:07:09 INFO - PROCESS | 25856 | 22:07:09 INFO - PROCESS | 25856 | [#4] prototype.html Cycles:180 Average:4749.38 Median:5360.41 stddev:1374.01 (25.6%) stddev-sans-first:1337.59 22:07:09 INFO - PROCESS | 25856 | Values: 349.8 356.9 359.9 362.7 368.1 5354.3 5365.8 5407.8 5414.7 5415.8 5313.5 5335.5 5343.9 5355.0 5385.4 3758.5 4019.4 4199.5 4242.7 4255.6 4261.3 4263.7 4264.7 4268.2 4269.6 4224.5 4237.2 4237.4 4237.4 4240.6 4155.7 4208.5 4226.5 4247.4 4257.6 3775.8 3790.0 3804.1 3804.4 3821.7 5243.3 5246.6 5255.2 5264.2 5271.6 4820.3 4850.5 4890.2 4900.6 4924.0 5577.9 5605.6 5613.3 5616.2 5621.4 4192.1 4200.1 4201.5 4205.4 4226.8 5406.2 5429.8 5821.9 5848.8 5874.9 4238.6 4251.9 4258.7 4267.7 4272.4 4320.8 4324.7 4355.0 4360.7 4375.2 4585.9 4889.6 4895.3 4926.3 4926.9 5635.4 5645.1 5653.3 5666.4 5704.7 1412.0 1415.0 1427.2 1437.8 1442.5 944.8 994.6 998.3 998.7 1009.1 5241.5 5279.3 5303.9 5306.4 5309.4 5569.7 5571.9 5574.7 5622.0 5639.4 5025.2 5233.6 5269.2 5298.0 5305.9 2929.4 2949.1 2969.5 2989.9 2998.3 5749.7 5756.3 5761.9 5800.4 5805.9 5599.5 5921.9 5924.4 5939.4 5953.0 6000.5 6039.3 6048.3 6059.5 6064.3 5554.5 5799.3 5802.7 5820.4 5834.7 5726.4 5753.0 5774.9 5783.2 5796.5 5784.2 5935.7 5942.0 5962.6 6017.0 4091.9 4202.4 4232.8 4243.6 4247.1 5659.4 5796.1 5810.8 5821.1 5821.2 5642.4 5645.6 5655.0 5663.7 5678.7 5570.4 5582.0 5600.7 5614.8 5651.2 5407.6 5540.8 5562.7 5564.8 5589.0 5596.2 5598.7 5608.8 5610.0 5646.9 5480.4 5486.5 5506.2 5517.9 5539.5 22:07:09 INFO - PROCESS | 25856 | 22:07:09 INFO - PROCESS | 25856 | [#5] yui.html Cycles:180 Average:17906.82 Median:27037.61 stddev:22767.74 (84.2%) stddev-sans-first:22795.12 22:07:09 INFO - PROCESS | 25856 | Values: 741.6 781.5 835.0 853.9 876.3 15050.9 15225.4 15471.3 15679.5 16617.2 16370.8 16738.0 16814.7 17013.8 17055.7 739.0 873.7 890.4 890.9 891.6 759.1 764.8 765.1 850.1 867.8 928.5 932.5 933.1 933.4 934.7 923.9 924.2 924.3 924.8 924.9 682.2 682.2 682.5 683.2 683.7 15384.5 15437.2 15993.3 16006.0 16008.4 14101.8 14596.6 14832.9 15339.2 15392.8 12540.2 12609.6 12758.2 12809.0 12975.2 3801.8 3833.3 3882.8 3924.2 4003.4 66267.7 68127.3 68451.0 69564.7 69859.0 10830.6 10861.7 11250.0 11358.1 11712.7 20895.9 20923.1 20947.6 21026.6 21690.9 7714.5 8475.8 8766.9 8829.3 8999.7 4631.0 4673.9 4683.9 4707.9 4881.4 3726.5 3851.3 3857.6 3860.6 3863.0 673.9 684.1 689.8 689.9 700.3 731.1 732.4 750.8 752.4 759.4 9381.0 9479.2 9747.6 9972.5 10006.0 543.8 553.6 568.9 570.4 572.4 4860.4 4915.0 4954.7 4959.2 5021.4 57789.7 57966.4 60395.7 89831.0 90192.0 38018.8 75770.2 77823.6 78745.0 80004.2 46391.5 72485.3 73559.6 74494.6 75612.2 9326.4 9374.0 9564.7 9633.0 9704.6 48647.3 49160.3 49365.8 50310.7 51659.0 62024.4 63181.7 67749.0 68809.3 69231.7 12483.8 12543.3 12673.9 12886.5 13099.5 13351.7 13807.0 14786.9 14842.7 15357.0 12952.4 13083.6 13201.7 13543.1 13584.6 13536.9 13757.7 13880.7 13904.5 14324.1 13448.7 13642.7 13897.4 14095.7 14297.1 10916.8 11019.6 11166.7 11272.5 11408.4 10023.8 10293.2 11072.9 11111.3 11230.6 22:07:09 INFO - PROCESS | 25856 | -------- Summary: end -------- 22:07:09 INFO - PROCESS | 25856 | 22:07:10 INFO - TEST-INFO | 25856: exit 0 22:07:10 INFO - TEST-OK | dromaeo_css | took 1114489ms 22:07:10 INFO - TEST-START | kraken 22:07:10 INFO - operating with platform_type : linux_ 22:07:10 INFO - Initialising browser for kraken test... 22:07:10 INFO - TEST-INFO | started process 26153 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYN7PRR/profile http://localhost:38397/getInfo.html) 22:07:14 INFO - PROCESS | 26153 | __metrics Screen width/height:1600/1200 22:07:14 INFO - PROCESS | 26153 | colorDepth:24 22:07:14 INFO - PROCESS | 26153 | Browser inner width/height: 1024/697 22:07:14 INFO - PROCESS | 26153 | __metrics 22:07:15 INFO - PROCESS | 26153 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:07:15 INFO - PROCESS | 26153 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:07:15 INFO - PROCESS | 26153 | console.error: PushService: 22:07:15 INFO - PROCESS | 26153 | stateChangeProcessEnqueue: Error transitioning state 22:07:15 INFO - PROCESS | 26153 | UnknownError 22:07:16 INFO - TEST-INFO | 26153: exit 0 22:07:16 INFO - Browser initialized. 22:07:16 INFO - Running cycle 1/1 for kraken test... 22:07:16 INFO - TEST-INFO | started process 26245 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYN7PRR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:07:28 INFO - PROCESS | 26245 | RSS: Main: 145117184 22:07:28 INFO - PROCESS | 26245 | 22:07:30 INFO - PROCESS | 26245 | Cycle 1(1): loaded http://localhost:38397/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38397/tests/kraken/driver.html?testName=audio-beat-detection) 22:07:31 INFO - PROCESS | 26245 | RSS: Main: 186568704 22:07:31 INFO - PROCESS | 26245 | 22:07:34 INFO - PROCESS | 26245 | Cycle 1(1): loaded http://localhost:38397/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38397/tests/kraken/driver.html?testName=audio-dft) 22:07:34 INFO - PROCESS | 26245 | RSS: Main: 176222208 22:07:34 INFO - PROCESS | 26245 | 22:07:37 INFO - PROCESS | 26245 | Cycle 1(1): loaded http://localhost:38397/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38397/tests/kraken/driver.html?testName=audio-fft) 22:07:38 INFO - PROCESS | 26245 | RSS: Main: 266956800 22:07:38 INFO - PROCESS | 26245 | 22:07:40 INFO - PROCESS | 26245 | Cycle 1(1): loaded http://localhost:38397/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38397/tests/kraken/driver.html?testName=audio-oscillator) 22:07:40 INFO - PROCESS | 26245 | RSS: Main: 174542848 22:07:40 INFO - PROCESS | 26245 | 22:07:42 INFO - PROCESS | 26245 | Cycle 1(1): loaded http://localhost:38397/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38397/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:07:43 INFO - PROCESS | 26245 | RSS: Main: 176357376 22:07:43 INFO - PROCESS | 26245 | 22:07:46 INFO - PROCESS | 26245 | Cycle 1(1): loaded http://localhost:38397/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38397/tests/kraken/driver.html?testName=imaging-darkroom) 22:07:46 INFO - PROCESS | 26245 | RSS: Main: 215490560 22:07:46 INFO - PROCESS | 26245 | 22:07:50 INFO - PROCESS | 26245 | Cycle 1(1): loaded http://localhost:38397/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38397/tests/kraken/driver.html?testName=imaging-desaturate) 22:07:50 INFO - PROCESS | 26245 | RSS: Main: 214437888 22:07:50 INFO - PROCESS | 26245 | 22:07:53 INFO - PROCESS | 26245 | Cycle 1(1): loaded http://localhost:38397/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38397/tests/kraken/driver.html?testName=json-parse-financial) 22:07:54 INFO - PROCESS | 26245 | RSS: Main: 212430848 22:07:54 INFO - PROCESS | 26245 | 22:07:55 INFO - PROCESS | 26245 | Cycle 1(1): loaded http://localhost:38397/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38397/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:07:56 INFO - PROCESS | 26245 | RSS: Main: 174616576 22:07:56 INFO - PROCESS | 26245 | 22:07:57 INFO - PROCESS | 26245 | Cycle 1(1): loaded http://localhost:38397/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38397/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:07:58 INFO - PROCESS | 26245 | RSS: Main: 181661696 22:07:58 INFO - PROCESS | 26245 | 22:08:00 INFO - PROCESS | 26245 | Cycle 1(1): loaded http://localhost:38397/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38397/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:08:01 INFO - PROCESS | 26245 | RSS: Main: 188502016 22:08:01 INFO - PROCESS | 26245 | 22:08:03 INFO - PROCESS | 26245 | Cycle 1(1): loaded http://localhost:38397/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38397/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:08:04 INFO - PROCESS | 26245 | RSS: Main: 196612096 22:08:04 INFO - PROCESS | 26245 | 22:08:06 INFO - PROCESS | 26245 | Cycle 1(1): loaded http://localhost:38397/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38397/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:08:07 INFO - PROCESS | 26245 | RSS: Main: 192708608 22:08:07 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | Cycle 1(1): loaded http://localhost:38397/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38397/tests/kraken/driver.html?testName=ai-astar) 22:08:09 INFO - PROCESS | 26245 | RSS: Main: 244224000 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | __start_tp_report 22:08:09 INFO - PROCESS | 26245 | _x_x_mozilla_page_load 22:08:09 INFO - PROCESS | 26245 | _x_x_mozilla_page_load_details 22:08:09 INFO - PROCESS | 26245 | |i|pagename|runs| 22:08:09 INFO - PROCESS | 26245 | |0;ai-astar;100;110;111;110;118;110;112;110;111;110 22:08:09 INFO - PROCESS | 26245 | |1;audio-beat-detection;140;123;118;153;119;118;118;117;148;118 22:08:09 INFO - PROCESS | 26245 | |2;audio-dft;160;161;161;159;161;165;160;159;169;160 22:08:09 INFO - PROCESS | 26245 | |3;audio-fft;74;76;75;113;75;76;75;77;96;76 22:08:09 INFO - PROCESS | 26245 | |4;audio-oscillator;81;81;124;82;82;82;97;81;82;117 22:08:09 INFO - PROCESS | 26245 | |5;imaging-gaussian-blur;116;117;118;117;117;117;117;116;116;117 22:08:09 INFO - PROCESS | 26245 | |6;imaging-darkroom;146;145;146;145;146;145;146;146;146;145 22:08:09 INFO - PROCESS | 26245 | |7;imaging-desaturate;85;84;85;85;85;84;84;84;84;84 22:08:09 INFO - PROCESS | 26245 | |8;json-parse-financial;53;50;52;51;50;51;50;50;49;49 22:08:09 INFO - PROCESS | 26245 | |9;json-stringify-tinderbox;59;60;60;59;59;60;60;59;60;60 22:08:09 INFO - PROCESS | 26245 | |10;stanford-crypto-aes;75;83;76;75;74;74;76;75;74;82 22:08:09 INFO - PROCESS | 26245 | |11;stanford-crypto-ccm;132;137;123;128;124;125;121;119;171;122 22:08:09 INFO - PROCESS | 26245 | |12;stanford-crypto-pbkdf2;157;152;155;161;152;154;156;155;154;161 22:08:09 INFO - PROCESS | 26245 | |13;stanford-crypto-sha256-iterative;61;62;63;62;61;61;61;62;60;62 22:08:09 INFO - PROCESS | 26245 | __end_tp_report 22:08:09 INFO - PROCESS | 26245 | __start_cc_report 22:08:09 INFO - PROCESS | 26245 | _x_x_mozilla_cycle_collect,1607 22:08:09 INFO - PROCESS | 26245 | __end_cc_report 22:08:09 INFO - PROCESS | 26245 | __startTimestamp1485238089454__endTimestamp 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | ------- Summary: start ------- 22:08:09 INFO - PROCESS | 26245 | Number of tests: 14 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | [#0] ai-astar Cycles:10 Average:110.20 Median:110.50 stddev:4.34 (3.9%) stddev-sans-first:2.60 22:08:09 INFO - PROCESS | 26245 | Values: 100.0 110.0 111.0 110.0 118.0 110.0 112.0 110.0 111.0 110.0 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | [#1] audio-beat-detection Cycles:10 Average:127.20 Median:121.00 stddev:14.10 (11.7%) stddev-sans-first:14.18 22:08:09 INFO - PROCESS | 26245 | Values: 140.0 123.0 118.0 153.0 119.0 118.0 118.0 117.0 148.0 118.0 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.00 stddev:3.14 (1.9%) stddev-sans-first:3.28 22:08:09 INFO - PROCESS | 26245 | Values: 160.0 161.0 161.0 159.0 161.0 165.0 160.0 159.0 169.0 160.0 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.00 stddev:12.89 (17.0%) stddev-sans-first:13.40 22:08:09 INFO - PROCESS | 26245 | Values: 74.0 76.0 75.0 113.0 75.0 76.0 75.0 77.0 96.0 76.0 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:82.00 stddev:16.41 (20.0%) stddev-sans-first:17.01 22:08:09 INFO - PROCESS | 26245 | Values: 81.0 81.0 124.0 82.0 82.0 82.0 97.0 81.0 82.0 117.0 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 22:08:09 INFO - PROCESS | 26245 | Values: 116.0 117.0 118.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 22:08:09 INFO - PROCESS | 26245 | Values: 146.0 145.0 146.0 145.0 146.0 145.0 146.0 146.0 146.0 145.0 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:0.52 (0.6%) stddev-sans-first:0.50 22:08:09 INFO - PROCESS | 26245 | Values: 85.0 84.0 85.0 85.0 85.0 84.0 84.0 84.0 84.0 84.0 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.50 stddev:1.27 (2.5%) stddev-sans-first:0.97 22:08:09 INFO - PROCESS | 26245 | Values: 53.0 50.0 52.0 51.0 50.0 51.0 50.0 50.0 49.0 49.0 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:0.52 (0.9%) stddev-sans-first:0.50 22:08:09 INFO - PROCESS | 26245 | Values: 59.0 60.0 60.0 59.0 59.0 60.0 60.0 59.0 60.0 60.0 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | [#10] stanford-crypto-aes Cycles:10 Average:76.40 Median:75.50 stddev:3.31 (4.4%) stddev-sans-first:3.47 22:08:09 INFO - PROCESS | 26245 | Values: 75.0 83.0 76.0 75.0 74.0 74.0 76.0 75.0 74.0 82.0 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | [#11] stanford-crypto-ccm Cycles:10 Average:130.20 Median:126.50 stddev:15.32 (12.1%) stddev-sans-first:16.24 22:08:09 INFO - PROCESS | 26245 | Values: 132.0 137.0 123.0 128.0 124.0 125.0 121.0 119.0 171.0 122.0 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.70 Median:155.50 stddev:3.20 (2.1%) stddev-sans-first:3.36 22:08:09 INFO - PROCESS | 26245 | Values: 157.0 152.0 155.0 161.0 152.0 154.0 156.0 155.0 154.0 161.0 22:08:09 INFO - PROCESS | 26245 | 22:08:09 INFO - PROCESS | 26245 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:62.00 stddev:0.85 (1.4%) stddev-sans-first:0.88 22:08:09 INFO - PROCESS | 26245 | Values: 61.0 62.0 63.0 62.0 61.0 61.0 61.0 62.0 60.0 62.0 22:08:09 INFO - PROCESS | 26245 | -------- Summary: end -------- 22:08:09 INFO - PROCESS | 26245 | 22:08:10 INFO - TEST-INFO | 26245: exit 0 22:08:10 INFO - TEST-OK | kraken | took 60237ms 22:08:10 INFO - SUITE-END | took 1174s 22:08:11 INFO - Completed test suite (00:19:35) 22:08:11 INFO - javascript benchmark 22:08:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3227.903162905113, 3235.1588586967387, 3254.853531591078, 3284.1297056280073, 3306.3222039481907, 5622.044252477079, 5690.975624387608, 5698.145278208271, 5700.087985922253, 5709.514691251248, 5708.600397972144, 5789.0448076067505, 5790.942090579093, 5791.986402379577, 5800.970995145019, 5170.793168273274, 5210.1403268460845, 5224.791008359661, 5259.395169555492, 5293.788248470057, 5076.517730815585, 5090.720010399426, 5290.92063619046, 5299.735013249322, 5333.333333333333, 5097.643164978453, 5148.176291793314, 5161.638685292031, 5181.844544663647, 5209.791608335681, 5179.093658609747, 5265.210218467241, 5265.473452654742, 5283.101872681654, 5322.5209731124, 4875.780589873429, 4978.850634480971, 5155.639105262633, 5184.974075129638, 5238.633295669305, 5493.505584497374, 5606.803761868315, 5633.211350410946, 5637.182608521783, 5659.773609055633, 6079.148919151322, 6119.326874043852, 6123.2039834821635, 6126.9387306127255, 6146.200993870768, 5928.347881733006, 5997.160397544348, 6129.754809807602, 6143.232095988002, 6147.200863887666, 5619.943800561983, 5724.570657200685, 5742.138679198112, 5790.826275211751, 5829.038208695518, 6281.0, 6305.211848518935, 6318.4313411793155, 6335.588186767845, 6339.587926784744, 5805.216295800013, 5851.707414629252, 6068.67526354106, 6072.180255665517, 6089.299730530957, 6013.0980352946535, 6057.757689692451, 6060.757569697251, 6122.693386293493, 6155.353687862799, 6117.096718007379, 6121.005657821351, 6126.864481135799, 6127.294493216227, 6134.7179259096165, 5822.388649191774, 5888.440598143169, 6162.907556386658, 6171.691415429211, 6177.2587289525545, 4604.263317869192, 4641.071785642817, 4651.139539185261, 4660.836870709509, 4673.532646735299, 4156.896077598084, 4163.5836416358725, 4168.18720349535, 4173.207090652766, 4195.769232692171, 5788.797392091256, 5804.564657650693, 5818.0981947798155, 5821.79623713168, 5833.095870140135, 5903.0, 5959.374265702038, 5979.372165922602, 6031.2159419276095, 6076.5442591805795, 5809.070548712186, 5810.88378232433, 5812.796552120655, 5815.709214539256, 5831.7667293307795, 5192.948070519322, 5208.166693329049, 5208.765605547759, 5211.452797456288, 5236.345456817898, 6121.574916084878, 6145.293291271471, 6208.782692605828, 6220.533459990609, 6297.281942409421, 6075.969620152047, 6081.087836824512, 6082.908756368746, 6101.938980610137, 6122.785702500391, 6262.68686565679, 6274.749010039547, 6279.215098112736, 6307.590006649553, 6333.904991425225, 5779.2198053263955, 5829.737661805314, 5899.882002360015, 5966.612170209096, 5974.133750606051, 6100.1154832548145, 6155.722992465439, 6161.907571386343, 6173.506119510518, 6185.597936134137, 6005.609635373687, 6010.909836352545, 6013.7293821779, 6013.9699301503215, 6035.788747393995, 5694.857628559319, 5816.651000939957, 5971.91042134377, 5977.462028417463, 5982.940170598238, 5897.469227769521, 5972.223610930725, 6013.9097913532205, 6061.909071363845, 6067.514598831995, 6102.542309326729, 6109.602875813058, 6115.0, 6115.021596544531, 6117.449429551361, 5624.971875140598, 5680.488756011813, 5711.828645140652, 5806.796762113475, 5940.584159108994], "value": 5616.769206825544, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.7179839576202, 627.6516533324007, 651.7425616881332, 667.2760055339955, 669.0365873142671, 34498.0, 46073.53926460739, 46854.297185542244, 46961.29558056632, 47107.29339059908, 46107.0, 46531.53468465323, 46544.53455465444, 46559.0, 46632.0, 8484.83030339396, 8682.739517814443, 8898.822023559542, 9045.683401080963, 9048.547572621375, 10654.733631659232, 10917.945410272938, 10927.344359338465, 11136.665900023012, 11154.330740155616, 11002.064824489928, 11054.944725276362, 11063.004329610374, 11066.889331106666, 11075.667729968114, 10841.13270938327, 10919.890801091968, 10950.835737463944, 10952.233343665946, 10956.0, 10610.681679549625, 10648.41433721145, 10670.573177072985, 10674.09270212033, 10685.893141068567, 44385.0, 44917.550824491664, 44963.77518112421, 44964.55035449669, 44976.0, 3607.9098022549383, 3667.7799332040163, 4305.095929854734, 4383.802728877208, 4487.9446168536615, 33263.33473330544, 33380.499292510634, 33388.499172512435, 34168.82915585431, 34615.480767788504, 3805.8858234253016, 3884.261990221849, 3911.9217615647813, 3933.272344616255, 3971.980140099281, 354587.0, 356034.0, 356706.9999999974, 357558.0, 357608.0, 63403.0, 63712.681436592524, 63790.0, 63817.68091159515, 63896.0, 36919.446208306894, 37512.81243593819, 37534.06164845846, 37640.43539346912, 66628.0, 15172.089674619558, 15196.164210968504, 15216.619584510476, 15218.239088045553, 15243.314050867737, 13409.128406653535, 13690.52083177084, 14102.506412275727, 14479.92760036193, 14618.707625847423, 20472.283470078477, 20657.483562910747, 20770.48073798167, 20861.165553378, 20864.47838804042, 7514.924850751532, 7608.543487390774, 7682.462227644123, 7799.5320280783335, 7827.686892524235, 1000.4747507558444, 1065.5577935156823, 1107.341132026443, 1133.2237417369129, 1153.7461758413135, 33259.0, 33895.83052084724, 33939.490907636406, 33951.4907276391, 34046.48930266048, 1049.8687664041995, 1127.8195488721929, 1169.3042639629407, 1182.0897908610493, 1195.1036722458157, 17851.196696148443, 18011.099445027696, 18183.363582274822, 19006.80993190078, 19957.20171193136, 492030.0, 493516.0, 494944.0, 497173.0, 498152.0, 63283.367166328666, 63617.68191159107, 63648.045279320846, 63729.68135159387, 63790.0, 1321.7819059855208, 1393.8257717785277, 1403.9157650541001, 1409.8237720284965, 1454.425501926733, 30656.0, 34698.65301346954, 34892.0, 35028.0, 35087.82456087703, 53096.46903530915, 53239.0, 53379.0, 53615.19577206423, 53822.0, 395825.04174957884, 401579.0, 406220.0, 406395.0, 406598.0, 28014.579781302888, 28593.0, 29907.700922991364, 30508.084757457313, 30586.541201881544, 48970.510294896594, 49644.0, 49685.25472117993, 49827.25950072781, 50365.24452133148, 41848.3722744153, 41994.58005419907, 42052.36921446242, 42419.0, 43582.56417435912, 42501.0, 42662.78668606637, 43229.783851080545, 43307.35038974355, 44666.776666116464, 42303.0, 42911.78544107259, 44215.778921106474, 44300.11399771922, 44476.55523444724, 35998.0, 38500.22999540049, 38547.614523855525, 38917.02707432337, 39016.80491597524, 28230.43539129165, 28275.434491309647, 28397.716022839508, 28641.570376443953, 28880.85559572189], "value": 21050.339062628736, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.1844044892234, 421.1576846307385, 423.8749568877181, 429.26595521657975, 433.3478115436268, 18889.527761805944, 19944.301949431774, 20559.588808223827, 20589.691154632674, 20726.067326970286, 20151.29470468534, 20409.183632654676, 20424.89787551064, 20611.69082463764, 20794.480137996557, 5604.355499117592, 5885.676287804169, 5885.705714714269, 5889.8822023559715, 5891.381404952483, 4224.352323890173, 5099.71951542665, 5782.421757824226, 5865.912011319833, 6528.510361722866, 6460.450861676763, 6477.514186436036, 6478.093066970628, 6478.352164783531, 6483.481321494269, 6417.647029413403, 6467.5472716909835, 6579.93420065798, 6591.934080659228, 6597.0, 5860.853478663025, 5877.882442351129, 5878.676672782995, 5879.0, 5889.911651325191, 24351.26946191617, 24360.26919192427, 24534.0, 24560.877195614084, 24755.38111547208, 2472.3819045238693, 2476.5170791695627, 2505.5615267328144, 2514.974850251492, 2560.6543116679195, 15153.924230378887, 15259.0, 15337.30982105808, 15613.297401616954, 16009.91995040029, 1922.9326973555858, 1985.2753744883157, 1992.681171012631, 2000.9799902000939, 2024.1093918675883, 81438.59280703618, 82586.58706706489, 82839.17160828375, 83068.0, 87350.0, 10994.0, 11127.666170014912, 11670.299782013106, 11721.35532545701, 11826.231294965799, 16303.0, 16351.918240408722, 16353.0, 16378.0, 16396.590085247964, 8458.519759042143, 8680.34897382699, 8818.33862460318, 8844.115588441104, 8870.778230544289, 7564.962175189089, 7583.2416758324825, 7703.614819259014, 7811.882821757678, 8220.17798220185, 7002.159740831133, 7041.718331266795, 7075.063848502917, 7184.784456466315, 7190.9280907191305, 1957.8727382720078, 2018.8889611071531, 2021.7573891133159, 2023.6559784836318, 2043.9591208175752, 943.9150476457014, 965.7440778193784, 1045.5765415006783, 1054.035557464911, 1087.9292845964987, 19971.900140499205, 20395.694064589043, 21080.683789743165, 21325.68011479829, 21357.0, 1012.5645972232011, 1016.7204018894863, 1067.829147336438, 1089.9182561307935, 1136.8465257190173, 10108.898911010796, 12021.639350819489, 12138.393080345948, 12155.5137794489, 12167.817482737766, 440709.0, 458707.0, 478338.0, 483865.0, 484185.0, 86046.0, 86192.56903715442, 86300.0, 86389.56805215933, 87232.0, 1039.594558122333, 1059.3537941855461, 1091.8962698543626, 1111.42704638761, 1115.486876037032, 17808.287668493635, 17858.5535361617, 18147.54631134232, 18639.534011649816, 18759.0, 41500.1699966005, 42302.36546451867, 42492.78753606212, 42559.5744042568, 42771.7861410691, 54935.72532137474, 55165.72417137888, 55257.0, 56456.435435645115, 57998.7100064497, 8460.0, 9133.954330228305, 9142.22291105237, 9264.073592640681, 9422.387544809566, 13060.934695326463, 13770.586882393543, 14015.579532614038, 14075.225862577247, 14200.28998550089, 11824.1131915105, 11974.820377694168, 12173.634790956285, 12180.147389682992, 12479.93760031194, 11645.068394528247, 11738.0, 11851.111166662362, 11972.102092342935, 12083.214591051905, 12107.757844842878, 12268.938655306667, 12414.193077449936, 12445.62663120108, 12586.056045796786, 8934.553272336487, 9114.13415725505, 9144.63421463134, 9349.48577828226, 9379.906200937903, 11871.050315974873, 11932.403379831147, 12147.574834880737, 12474.313912734886, 12565.874341256836], "value": 9813.058548479708, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1268.099649249033, 1519.8860085493586, 1528.4387377410635, 1731.1603872122018, 1864.7109697996807, 7967.521948683083, 8866.069062748415, 9124.361294709357, 9131.178193962542, 9135.725928222148, 8962.0, 9037.18665320121, 9051.637934482613, 9066.818663626724, 9070.818583628323, 2437.9512409751837, 2466.1984854922134, 2467.7902378297777, 2467.962980555293, 2471.9629205561932, 2407.8916448759846, 2474.480359124586, 2488.850668959859, 2496.8002559795264, 2503.198976327576, 2441.560519106569, 2445.9633105503344, 2452.619843924195, 2457.45935894103, 2471.369800700828, 2303.516261585069, 2341.9063237470564, 2348.8942997565064, 2385.1175065225802, 2426.344886880532, 2391.988040059806, 2422.6608274841537, 2428.514297140579, 2429.829911906167, 2432.6959130108735, 8879.200871921557, 8942.821143577157, 8970.686025989124, 9110.635574577009, 9231.584578693975, 5583.162525621149, 5728.42715728428, 5742.081266997302, 5751.568632352591, 5755.971220143914, 3345.2305969626877, 3415.3681568909833, 3418.7094097001786, 3446.8104254266004, 3692.058525076115, 1068.417712346765, 1076.0508932218777, 1102.189890430541, 1106.80630889594, 1107.6510899066768, 12220.205686630341, 12329.260244385276, 12367.381630918419, 12410.875891241152, 12411.627651170389, 4673.252279635242, 4752.239641657318, 4766.453669377403, 4782.258749893772, 4895.534924182197, 9979.451130187765, 10004.54979525923, 10009.299349045643, 10019.0, 10027.79944401108, 5467.72661366938, 5473.370562385297, 5487.75305111271, 5488.341399032141, 5501.807436739695, 4350.236457333983, 4602.309653551994, 4686.484486706459, 4710.034442939189, 5087.542121209109, 3957.1887763008513, 4044.312466880637, 4160.2303573838335, 4171.249175148441, 4182.519010313792, 1234.2902830872106, 1253.423425224407, 1255.8681338459512, 1262.9179103358251, 1272.9490820367082, 905.3391024551983, 970.3013830042358, 1006.1146191351564, 1008.7175590834578, 1051.2115913065202, 6192.5974811638, 6207.534434917309, 6293.622382657055, 6301.275353334425, 6478.449331806849, 469.4249544308195, 477.81365267545686, 485.36902027364283, 497.0407114269495, 503.692747424071, 4274.380214868889, 4364.563543645673, 4364.694471387036, 4395.868123956287, 4409.845655402046, 10994.120470362352, 11358.432078396047, 11395.601153959571, 11442.0, 11484.827727584094, 11192.272502287326, 11200.495977681025, 11284.322940623424, 11345.319280843176, 11416.143789215675, 8667.913320866837, 8760.386772925835, 8767.0, 8768.912310876936, 8780.824383512294, 5971.641701497924, 6048.60684055535, 6126.203593532812, 6152.354002829638, 6237.376262373908, 10039.899601003897, 10311.639092632022, 10363.585456581954, 10437.373757574569, 10448.791024179322, 9516.282167906806, 9529.57116929726, 10496.05535501778, 10561.260711750103, 10625.36247825102, 7783.688652453839, 7804.570748608881, 8150.470219435718, 8227.670893164206, 8228.382871284797, 8756.387052906235, 8972.37193396456, 9048.864267035868, 9052.81894362096, 9061.818763624822, 7818.452708310364, 7830.725924592612, 7883.448158628841, 7973.920260797549, 8083.8383232333845, 7891.84216315682, 7901.486403383762, 7977.960110199412, 8035.357171426155, 8170.0604430488875, 7416.7404140854815, 7471.514351567131, 7544.207858174813, 7559.395248380007, 7572.053493313336, 5337.733113344395, 5390.703511306916, 5449.782008719607, 5454.4545545445135, 5531.391546929915, 5504.587155963239, 5594.608377413542, 5644.3508996465935, 5653.349864765604, 5717.742701578522], "value": 4705.789692375539, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.8076058168007, 356.89828398906303, 359.8776416018553, 362.71889285803496, 368.0798004987531, 5354.330708661418, 5365.812196573121, 5407.810281738011, 5414.702191379472, 5415.756290966906, 5313.521783039526, 5335.493128152829, 5343.866403339918, 5355.0, 5385.415000799619, 3758.5113935188524, 4019.4372787809657, 4199.538050814408, 4242.703010789246, 4255.63827074699, 4261.339492378686, 4263.722858014219, 4264.701470897038, 4268.231718290706, 4269.6370808481315, 4224.471941007374, 4237.194932962727, 4237.4279472271155, 4237.449131612886, 4240.579405899012, 4155.667546596265, 4208.452901122834, 4226.450561426995, 4247.447831781864, 4257.616814486695, 3775.7734535927657, 3789.9767062892865, 3804.144067584799, 3804.3912973924316, 3821.6942644588366, 5243.318368612094, 5246.554042906358, 5255.211718242256, 5264.184051472028, 5271.630985830993, 4820.349252850855, 4850.490698476679, 4890.168671325883, 4900.558949694509, 4924.0, 5577.8605534861545, 5605.626621477764, 5613.270274864241, 5616.157576363537, 5621.381648018714, 4192.11965487248, 4200.096979149467, 4201.537830838605, 4205.411242426063, 4226.830926762926, 5406.216098665672, 5429.828068772483, 5821.88356232873, 5848.795292164754, 5874.9118763218585, 4238.554951730086, 4251.851185208503, 4258.68059895509, 4267.701260911706, 4272.401863739017, 4320.848770293088, 4324.675649326313, 4355.041890784086, 4360.738355698605, 4375.190589740844, 4585.862424127282, 4889.579658069279, 4895.265710143507, 4926.261060840902, 4926.9306891105825, 5635.408282130399, 5645.125005624135, 5653.321601407775, 5666.376698563155, 5704.657720536781, 1411.964700882486, 1414.9717005660034, 1427.2444892781937, 1437.7563407652515, 1442.5383877159418, 944.8346539355723, 994.6469003503797, 998.2662742883974, 998.6754304850807, 1009.1371877045168, 5241.50205730455, 5279.3370088422025, 5303.893922121536, 5306.363236411578, 5309.389420216725, 5569.721513924287, 5571.9442805572235, 5574.689947862249, 5622.0484245771295, 5639.407862174495, 5025.221090730943, 5233.581313494911, 5269.182132164397, 5297.973510132425, 5305.867353316198, 2929.399473108008, 2949.0656796487206, 2969.5248760198265, 2989.8505074746176, 2998.29097414476, 5749.655020698771, 5756.33802112754, 5761.8559536011935, 5800.419958004166, 5805.912911306334, 5599.468050535193, 5921.851953701192, 5924.437178468039, 5939.406059394026, 5952.970235148883, 6000.549958753111, 6039.335673075958, 6048.334683184846, 6059.48494377974, 6064.332923378513, 5554.527865131509, 5799.3040835100055, 5802.738876750472, 5820.417958204146, 5834.679092649945, 5726.370099289158, 5752.9712351437975, 5774.9133762994425, 5783.248177736868, 5796.536277097906, 5784.21913041734, 5935.7032148391545, 5942.0, 5962.642241465526, 6016.969915150396, 4091.8772436826944, 4202.41166236721, 4232.814811852686, 4243.6180743731975, 4247.088810035435, 5659.377468478382, 5796.1305804129715, 5810.825675229581, 5821.09772985188, 5821.243238378984, 5642.379338272869, 5645.604807663424, 5654.971725141348, 5663.716814159358, 5678.716064196857, 5570.359408668055, 5581.995240856522, 5600.719964001865, 5614.803481878114, 5651.180578816131, 5407.567394608343, 5540.7783688652, 5562.72186390687, 5564.777408903598, 5589.0, 5596.1605759136455, 5598.748056337556, 5608.775648973995, 5610.0, 5646.88706225865, 5480.424555421623, 5486.533644640251, 5506.229127922014, 5517.862053448696, 5539.473749993744], "value": 4317.51497373809, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.6291854072964, 781.5467029123106, 835.0480452284393, 853.8932633420823, 876.2726936642586, 15050.924745376271, 15225.390984360625, 15471.303791329388, 15679.45121920733, 16617.16914154292, 16370.754438683429, 16738.0, 16814.747778783298, 17013.82986170138, 17055.658886822257, 738.9815254618651, 873.7378786364097, 890.3856339126015, 890.8708237305586, 891.61660485991, 759.0549765541908, 764.8164440534288, 765.0589774577273, 850.0819115355432, 867.8134201146752, 928.517171071046, 932.5290728182258, 933.0948979489884, 933.3886304470583, 934.728928610702, 923.9214666753334, 924.191332583989, 924.3298608508777, 924.8104138651561, 924.8982611912684, 682.205230905994, 682.2358957967059, 682.4881338995754, 683.1597135523334, 683.6547543490506, 15384.538463846102, 15437.228138593026, 15993.28030238642, 16006.0, 16008.359665613361, 14101.78847317291, 14596.562103136923, 14832.851671483255, 15339.233038348038, 15392.769108463383, 12540.24758514492, 12609.558665446666, 12758.170718903242, 12809.0, 12975.221486710732, 3801.752886062397, 3833.310004199243, 3882.786446745428, 3924.2347742190286, 4003.399490076512, 66267.66866165686, 68127.31872681259, 68451.0, 69564.6521767393, 69859.0, 10830.620928267474, 10861.67414977552, 11250.0, 11358.148138889615, 11712.70718232034, 20895.8955205223, 20923.058462368925, 20947.581048379252, 21026.579468410546, 21690.89154554217, 7714.498557593738, 8475.788105297417, 8766.912330876738, 8829.337799664921, 8999.685011024583, 4630.9536904631195, 4673.929891051637, 4683.882902927454, 4707.8587642370785, 4881.414230292387, 3726.4969229153717, 3851.30676478231, 3857.633524815138, 3860.5946375630715, 3863.03424143964, 673.865226954611, 684.1106561470069, 689.8103021669081, 689.8689249042666, 700.3346820520526, 731.0861423220974, 732.3555271361168, 750.8047907543969, 752.3792870881545, 759.3735168486021, 9380.95309523448, 9479.24166066713, 9747.610095596097, 9972.501374931224, 10006.0, 543.8368489453148, 553.5848113914564, 568.8728276355018, 570.4295704295704, 572.4475880775072, 4860.441049279308, 4914.950850491521, 4954.653174277838, 4959.20652695567, 5021.447640759514, 57789.71105144447, 57966.420335796945, 60395.69802150961, 89831.0, 90192.0, 38018.80990595029, 75770.24229757742, 77823.61088194523, 78745.0, 80004.19995799968, 46391.536084639396, 72485.2751472489, 73559.63220183972, 74494.6275268631, 75612.24387756162, 9326.393784403992, 9373.953130234306, 9564.66523671668, 9632.95183524078, 9704.6118155273, 48647.27029094637, 49160.26259606037, 49365.75317123535, 50310.748446259, 51659.0, 62024.37975620186, 63181.68409158109, 67749.0, 68809.31190688029, 69231.65384173047, 12483.812742809047, 12543.3101179436, 12673.93663031679, 12886.548970785978, 13099.541516046893, 13351.732965340832, 13807.0, 14786.852131478978, 14842.703145937237, 15357.001794883743, 12952.352382380655, 13083.607491775261, 13201.669958250737, 13543.119697219649, 13584.592462226148, 13536.932315338361, 13757.724845502833, 13880.652983675489, 13904.51334203298, 14324.14055156694, 13448.663783405493, 13642.658933526742, 13897.444102235797, 14095.718085638435, 14297.142171469744, 10916.781664366827, 11019.559217631206, 11166.720831979266, 11272.492737826653, 11408.372539510407, 10023.799524009624, 10293.176545876162, 11072.889271107186, 11111.333320000826, 11230.55077796879], "value": 7416.443581292113, "unit": "score"}], "name": "dromaeo_css", "value": 7477.840821508704, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 110.0, 111.0, 110.0, 118.0, 110.0, 112.0, 110.0, 111.0, 110.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 123.0, 118.0, 153.0, 119.0, 118.0, 118.0, 117.0, 148.0, 118.0], "value": 127.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 159.0, 161.0, 165.0, 160.0, 159.0, 169.0, 160.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 113.0, 75.0, 76.0, 75.0, 77.0, 96.0, 76.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 124.0, 82.0, 82.0, 82.0, 97.0, 81.0, 82.0, 117.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 85.0, 85.0, 84.0, 84.0, 84.0, 84.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 52.0, 51.0, 50.0, 51.0, 50.0, 50.0, 49.0, 49.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 59.0, 59.0, 60.0, 60.0, 59.0, 60.0, 60.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 83.0, 76.0, 75.0, 74.0, 74.0, 76.0, 75.0, 74.0, 82.0], "value": 76.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 137.0, 123.0, 128.0, 124.0, 125.0, 121.0, 119.0, 171.0, 122.0], "value": 130.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 152.0, 155.0, 161.0, 152.0, 154.0, 156.0, 155.0, 154.0, 161.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 63.0, 62.0, 61.0, 61.0, 61.0, 62.0, 60.0, 62.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1451.8000000000002, "alertThreshold": 2.0}]} 22:08:11 INFO - Return code: 0 22:08:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:08:11 INFO - # TBPL SUCCESS # 22:08:11 INFO - Running post-action listener: _resource_record_post_action 22:08:11 INFO - [mozharness: 2017-01-24 06:08:11.237113Z] Finished run-tests step (success) 22:08:11 INFO - Running post-run listener: _resource_record_post_run 22:08:11 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 0; Write bytes: 320159744; Read time: 0; Write time: 321548 22:08:11 INFO - TinderboxPrint: CPU usage
12.9% 22:08:11 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 22:08:11 INFO - TinderboxPrint: I/O write bytes / time
320,159,744 / 321,548 22:08:11 INFO - TinderboxPrint: CPU idle
8,263.3 (87.1%) 22:08:11 INFO - TinderboxPrint: CPU user
1,185.1 (12.5%) 22:08:11 INFO - TinderboxPrint: Swap in / out
0 / 0 22:08:11 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 101236736; Read time: 0; Write time: 154240 22:08:11 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 218923008; Read time: 0; Write time: 167308 22:08:11 INFO - Running post-run listener: _upload_blobber_files 22:08:11 INFO - Blob upload gear active. 22:08:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:08:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:08:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:08:12 INFO - (blobuploader) - INFO - Open directory for files ... 22:08:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:08:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:08:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:13 INFO - (blobuploader) - INFO - Done attempting. 22:08:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:08:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:08:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:17 INFO - (blobuploader) - INFO - Done attempting. 22:08:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:08:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:08:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:17 INFO - (blobuploader) - INFO - Done attempting. 22:08:17 INFO - (blobuploader) - INFO - Iteration through files over. 22:08:17 INFO - Return code: 0 22:08:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:08:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:08:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ecaf80e4755f44a5bc3bf24d81eaf7ae915cf09175c758086db835c708a7aa0e59d2deabf0d2056819c2891fd4093cd01c311811748edf62b9c506f84ef4189b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5720eac0cbc8f1216ee498b20dc8ea2518d50a3c50dff0eeed7522c73ea62401d637ed603d86ef5c43b67faecd94fc54bfe0b561c6b7bdba5c35ef68541100be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8eb7227f64c1b521fb7ec26959c4b394fce6032647772da2ad739b862d1f394eb6a91147ceae9b837b0d22f62651968d6a9d1805f3d7d8310321cf80b07de185"} 22:08:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:08:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:08:17 INFO - Contents: 22:08:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ecaf80e4755f44a5bc3bf24d81eaf7ae915cf09175c758086db835c708a7aa0e59d2deabf0d2056819c2891fd4093cd01c311811748edf62b9c506f84ef4189b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5720eac0cbc8f1216ee498b20dc8ea2518d50a3c50dff0eeed7522c73ea62401d637ed603d86ef5c43b67faecd94fc54bfe0b561c6b7bdba5c35ef68541100be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8eb7227f64c1b521fb7ec26959c4b394fce6032647772da2ad739b862d1f394eb6a91147ceae9b837b0d22f62651968d6a9d1805f3d7d8310321cf80b07de185"} 22:08:17 INFO - Running post-run listener: copy_logs_to_upload_dir 22:08:17 INFO - Copying logs to upload dir... 22:08:17 INFO - mkdir: /builds/slave/test/build/upload/logs 22:08:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.797131 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2017-01-23 22:08:17.601643) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-23 22:08:17.605064) ========= 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=ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ecaf80e4755f44a5bc3bf24d81eaf7ae915cf09175c758086db835c708a7aa0e59d2deabf0d2056819c2891fd4093cd01c311811748edf62b9c506f84ef4189b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5720eac0cbc8f1216ee498b20dc8ea2518d50a3c50dff0eeed7522c73ea62401d637ed603d86ef5c43b67faecd94fc54bfe0b561c6b7bdba5c35ef68541100be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8eb7227f64c1b521fb7ec26959c4b394fce6032647772da2ad739b862d1f394eb6a91147ceae9b837b0d22f62651968d6a9d1805f3d7d8310321cf80b07de185"} build_url:https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011396 build_url: 'https://queue.taskcluster.net/v1/task/EPqFj8PwRu2rDj9DpUnGJg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ecaf80e4755f44a5bc3bf24d81eaf7ae915cf09175c758086db835c708a7aa0e59d2deabf0d2056819c2891fd4093cd01c311811748edf62b9c506f84ef4189b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5720eac0cbc8f1216ee498b20dc8ea2518d50a3c50dff0eeed7522c73ea62401d637ed603d86ef5c43b67faecd94fc54bfe0b561c6b7bdba5c35ef68541100be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8eb7227f64c1b521fb7ec26959c4b394fce6032647772da2ad739b862d1f394eb6a91147ceae9b837b0d22f62651968d6a9d1805f3d7d8310321cf80b07de185"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-23 22:08:17.631159) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 22:08:17.631593) ========= 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=ba164b0a7b0ec7655860e956000001c1-1485236861.612725-1528894983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 22:08:17.716913) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-23 22:08:17.717292) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-23 22:08:17.717699) ========= ========= Total master_lag: 0.17 =========