Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.01.2021 13:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16724532 kB
MemAvailable 31260592 kB
Buffers 0 kB
Cached 14833280 kB
SwapCached 4864 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-01-08 13:26:55
DTS_END                         2021-01-08 13:26:55.4470
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48424
TRN_ID                          230765
ATT_ID                          626
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8960.23 20072 1075228
customer order: creation 168.56 444 20227
customer order: refuse 14.80 298 1776
order to supplier: creation 44.22 1297 5306
order to supplier: removal 3.41 112080 409
invoice (draft): creation 44.28 3393 5314
invoice (draft): removal 50.89 818 6107
invoice accept: apply 40.91 17683 4909
invoice accept: cancel 46.01 942 5521
customer reserve: creation 3775.85 174 453102
customer reserve: removal 4482.39 70 537887
realization accept: apply 82.38 172 9885
realization accept: cancel 80.20 112 9624
payment from customer: creation 58.39 111 7007
payment from customer: removal 5.48 67 658
payment to supplier: creation 48.39 116 5807
payment to supplier: removal 3.08 94 370
service: total inventory turnovers 3.84 3108 461
service: total monetary turnovers 6.13 542 736
service: aggregate perf. data 0.98 11719 117
service: refresh index statistics 0.04 248197 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-01-2021 13:27:37 to 08-01-2021 13:27:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1762
TEST_TIME 2 4678
TEST_TIME 3 5560
TEST_TIME 4 5651
TEST_TIME 5 5846
TEST_TIME 6 5439
TEST_TIME 7 5400
TEST_TIME 8 5603
TEST_TIME 9 5817
TEST_TIME 10 6029
TEST_TIME 11 5877
TEST_TIME 12 5924
TEST_TIME 13 6476
TEST_TIME 14 6508
TEST_TIME 15 7425
TEST_TIME 16 7649
TEST_TIME 17 7714
TEST_TIME 18 8215
TEST_TIME 19 8353
TEST_TIME 20 8784
TEST_TIME 21 9342
TEST_TIME 22 9428
TEST_TIME 23 9917
TEST_TIME 24 10417
TEST_TIME 25 10989
TEST_TIME 26 11120
TEST_TIME 27 10834
TEST_TIME 28 10705
TEST_TIME 29 10458
TEST_TIME 30 10208
TEST_TIME 31 10322
TEST_TIME 32 10179
TEST_TIME 33 10155
TEST_TIME 34 10110
TEST_TIME 35 9969
TEST_TIME 36 9882
TEST_TIME 37 9716
TEST_TIME 38 9785
TEST_TIME 39 9927
TEST_TIME 40 9813
TEST_TIME 41 9699
TEST_TIME 42 9835
TEST_TIME 43 10141
TEST_TIME 44 10101
TEST_TIME 45 10009
TEST_TIME 46 9942
TEST_TIME 47 10078
TEST_TIME 48 10148
TEST_TIME 49 10034
TEST_TIME 50 9863
TEST_TIME 51 9816
TEST_TIME 52 9735
TEST_TIME 53 9620
TEST_TIME 54 9497
TEST_TIME 55 9460
TEST_TIME 56 9457
TEST_TIME 57 9418
TEST_TIME 58 9340
TEST_TIME 59 9322
TEST_TIME 60 9313
TEST_TIME 61 9337
TEST_TIME 62 9438
TEST_TIME 63 9472
TEST_TIME 64 9450
TEST_TIME 65 9681
TEST_TIME 66 9641
TEST_TIME 67 9730
TEST_TIME 68 9660
TEST_TIME 69 9610
TEST_TIME 70 9547
TEST_TIME 71 9457
TEST_TIME 72 9483
TEST_TIME 73 9433
TEST_TIME 74 9379
TEST_TIME 75 9326
TEST_TIME 76 9327
TEST_TIME 77 9293
TEST_TIME 78 9316
TEST_TIME 79 9321
TEST_TIME 80 9283
TEST_TIME 81 9216
TEST_TIME 82 9198
TEST_TIME 83 9292
TEST_TIME 84 9281
TEST_TIME 85 9245
TEST_TIME 86 9151
TEST_TIME 87 9078
TEST_TIME 88 9128
TEST_TIME 89 9068
TEST_TIME 90 9040
TEST_TIME 91 9009
TEST_TIME 92 8969
TEST_TIME 93 8962
TEST_TIME 94 9017
TEST_TIME 95 9026
TEST_TIME 96 9129
TEST_TIME 97 9122
TEST_TIME 98 9079
TEST_TIME 99 9084
TEST_TIME 100 9044
TEST_TIME 101 9059
TEST_TIME 102 9214
TEST_TIME 103 9197
TEST_TIME 104 9203
TEST_TIME 105 9125
TEST_TIME 106 9099
TEST_TIME 107 9063
TEST_TIME 108 9023
TEST_TIME 109 8986
TEST_TIME 110 8968
TEST_TIME 111 8930
TEST_TIME 112 8919
TEST_TIME 113 8951
TEST_TIME 114 8985
TEST_TIME 115 8946
TEST_TIME 116 8991
TEST_TIME 117 8979
TEST_TIME 118 8998
TEST_TIME 119 8998
TEST_TIME 120 8965

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-01-2021 13:27:41 to 08-01-2021 13:27:44.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1493542 1493542 0 0.00 0 22512 6 0 0
doc_list_biud 1493542 1493542 0 0.00 0 71679 31 0 0
sp_add_invoice_to_stock 5030 4909 121 2.40 61 154896 17684 0 121
sp_cancel_adding_invoice 5521 5521 0 0.00 6 60617 943 0 0
sp_cancel_client_order 1776 1776 0 0.00 5 6607 299 0 0
sp_cancel_customer_reserve 537887 537887 0 0.00 1 33981 70 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 2146 68 0 0
sp_cancel_pay_to_supplier 370 370 0 0.00 3 9004 95 0 0
sp_cancel_supplier_invoice 6115 6107 8 0.13 2 71686 819 0 8
sp_cancel_supplier_order 409 409 0 0.00 8 1178857 112081 0 0
sp_cancel_write_off 9624 9624 0 0.00 1 6942 112 0 0
sp_client_order 20227 20227 0 0.00 4 30906 445 0 0
sp_customer_reserve 453102 453102 0 0.00 0 42113 175 0 0
sp_fill_shopping_cart 30844 30844 0 0.00 1 27173 40 0 0
sp_fill_shopping_cart_clo_res 453097 453097 0 0.00 0 21069 20 0 0
sp_get_clo_for_invoice 4904 4904 0 0.00 0 24001 381 0 0
sp_kill_cost_storno 16173 16173 0 0.00 0 6235 52 0 0
sp_kill_qstorno_ret_qs2qd 1091914 1091914 0 0.00 0 71677 37 0 0
sp_kill_qty_storno 576112 576112 0 0.00 0 71679 80 0 0
sp_lock_selected_doc 20445 20445 0 0.00 0 7365 5 0 0
sp_make_cost_storno 27602 27602 0 0.00 0 12263 51 0 0
sp_multiply_rows_for_pdistr 27602 27602 0 0.00 0 7411 28 0 0
sp_multiply_rows_for_qdistr 20227 20227 0 0.00 0 17475 268 0 0
sp_pay_from_customer 7007 7007 0 0.00 4 22655 111 0 0
sp_pay_to_supplier 5807 5807 0 0.00 5 23426 116 0 0
sp_payment_common 12814 12814 0 0.00 1 7252 18 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 0 6596 269 0 0
sp_qd_handle_on_invoice_upd_sts 10424 10424 0 0.00 0 60370 376 0 0
sp_qd_handle_on_reserve_upd_sts 9885 9885 0 0.00 0 11377 52 0 0
sp_reserve_write_off 9885 9885 0 0.00 7 22766 172 0 0
sp_supplier_invoice 5314 5314 0 0.00 7 139865 3393 0 0
sp_supplier_order 5306 5306 0 0.00 5 97270 1297 0 0
srv_aggregate_perf_data 117 117 0 0.00 2899 161646 11720 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1277 140677 48847 0 0
srv_find_qd_qs_mism 1027908 1027908 0 0.00 0 22511 5 0 0
srv_make_invnt_saldo 461 461 0 0.00 38 99991 3109 0 0
srv_make_money_saldo 736 736 0 0.00 1 21687 542 0 0
srv_recalc_idx_stat 5 5 0 0.00 138081 411402 248197 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4910 4910 0 0.00 0 7468 137 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 2179 75 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 1632 10 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 597 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 1708 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 370 370 0 0.00 0 1492 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 348 7 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 18766 1110 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 1707 6 0 0
t$perf-norm:sp_client_order 20227 20227 0 0.00 0 7638 15 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 673 3 0 0
t$perf-norm:sp_pay_from_customer 7007 7007 0 0.00 0 4853 9 0 0
t$perf-norm:sp_pay_to_supplier 5807 5807 0 0.00 0 3146 6 0 0
t$perf-norm:sp_reserve_write_off 9885 9885 0 0.00 0 5817 8 0 0
t$perf-norm:sp_supplier_invoice 5314 5314 0 0.00 0 7528 18 0 0
t$perf-norm:sp_supplier_order 5306 5306 0 0.00 0 5965 15 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 646 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 266 5 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 951 7 0 0
t$perf-norm:srv_make_money_saldo 736 736 0 0.00 0 1055 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10048 9806 242 2.40 0 5230 17 0 242
v_all_customers 36934 36934 0 0.00 0 20 1 0 0
v_all_suppliers 29814 29814 0 0.00 0 20 1 0 0
v_all_wares 40454 40454 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 279 5 0 0
v_cancel_client_order 3552 3552 0 0.00 0 1865 6 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 1446 7 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 3098 8 0 0
v_cancel_payment_to_supplier 740 740 0 0.00 0 3509 9 0 0
v_cancel_supplier_invoice 866 850 16 1.84 0 3857 16 0 16
v_cancel_supplier_order 818 818 0 0.00 0 2778 11 0 0
v_cancel_write_off 1884 1884 0 0.00 0 5129 16 0 0
v_max_id_clo_ord 5305 5305 0 0.00 0 348 1 0 0
v_max_id_clo_res 995 995 0 0.00 0 1711 12 0 0
v_max_id_ord_sup 5312 5312 0 0.00 0 5146 5 0 0
v_max_non_paid_invoice 1517 1517 0 0.00 0 381 2 0 0
v_max_non_paid_realizn 3646 3646 0 0.00 0 6319 5 0 0
v_min_id_clo_ord 5305 5305 0 0.00 0 1359 3 0 0
v_min_id_clo_res 995 995 0 0.00 0 7885 18 0 0
v_min_id_ord_sup 5312 5312 0 0.00 0 5161 7 0 0
v_min_non_paid_invoice 5807 5807 0 0.00 0 5303 9 0 0
v_min_non_paid_realizn 7007 7007 0 0.00 0 5264 13 0 0
v_our_firm 10242 10242 0 0.00 0 20 1 0 0
v_reserve_write_off 19770 19770 0 0.00 0 4778 6 0 0
x_lock_depdocs_on_canc_invoice 11203 11203 0 0.00 0 19240 98 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 3369 267 0 0
x_make_qstorno_1000_1200 5306 5306 0 0.00 2 92594 1227 0 0
x_make_qstorno_1200_2000 5314 5314 0 0.00 3 119388 3239 0 0
x_make_qstorno_2100_3300 453102 453102 0 0.00 0 40987 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-01-2021 13:27:45 to 08-01-2021 13:27:49.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:59 14 8192000000 8800378944 8812421120 5509856 0.067 2 1 2 0 5640560 68400 62304
10:58:00 31892 8192000000 9714252784 10006147072 786963024 8.764 100 89 178 1 816429056 7491904 21974128
10:59:43 17936 8192000000 9750686160 10051321856 826422208 9.163 100 90 180 0 861171440 13345712 21403520
11:01:05 19746 8192000000 9793379216 10112155648 856033376 9.460 100 75 150 1 897535920 24766848 16735696
11:02:40 9427 8192000000 9881992096 10213445632 924999824 10.145 100 79 158 2 982122464 40064960 17057680
11:03:54 8770 8192000000 9949258240 10294398976 975937600 10.645 100 90 180 1 1055124096 60625824 18560672
11:05:06 4641 8192000000 9977251472 10329812992 1003985872 10.917 100 83 166 1 1086857952 65318320 17553760
11:06:13 5654 8192000000 10007479408 10369937408 1028392960 11.153 100 80 158 1 1119480624 75499600 15588064
11:07:21 2370 8192000000 10036453008 10401411072 1049746576 11.358 100 87 174 2 1142323552 74516512 18060464
11:08:25 2341 8192000000 10034819136 10404208640 1057853248 11.436 100 89 179 1 1149358624 72507680 18997696
11:09:30 1952 8192000000 10039092816 10420584448 1070179696 11.554 100 84 168 0 1161037648 73164528 17693424
11:10:33 3993 8192000000 10000750384 10389651456 1074715424 11.597 100 58 117 0 1132158304 44860992 12581888
11:11:42 35018 8192000000 10010855360 10405621760 1076637792 11.615 100 39 76 2 1097623888 12992704 7993392
11:13:17 8546 8192000000 9994924240 10393964544 1083591488 11.682 100 37 73 1 1121363856 29028480 8743888
11:14:26 6578 8192000000 10009271088 10410696704 1087961376 11.723 100 54 105 2 1120621392 21306304 11353712
11:15:34 5507 8192000000 10001426560 10397188096 1086768784 11.712 100 43 86 2 1115400704 18332784 10299136
11:16:40 34452 8192000000 10002329216 10391310336 1067783168 11.531 98 52 100 1 1100427824 20725168 11919488
11:18:15 4030 8192000000 10011659792 10400088064 1052934000 11.389 99 43 86 2 1121384848 58645952 9804896
11:19:21 7835 8192000000 9928988416 10300792832 999209312 10.871 97 36 71 0 1022158336 14365984 8583040
11:20:29 11141 8192000000 9929612224 10316070912 1018090256 11.054 99 28 53 1 1048866128 24694512 6081360
11:21:41 19827 8192000000 9928555088 10319491072 991972560 10.801 96 31 59 2 1022961136 24387856 6600720
11:23:05 5548 8192000000 9933623968 10312232960 1007273024 10.949 98 52 104 0 1027352880 7512208 12567648
11:24:12 6650 8192000000 9951550304 10351443968 1045315680 11.316 100 30 59 6 1068684416 17161168 6207568
11:25:19 63560 8192000000 9971204112 10383368192 1060091280 11.457 100 23 46 0 1088812368 23030992 5690096
11:27:31 24250 8192000000 10011105504 10405912576 1085909488 11.704 100 47 94 1 1124035856 28977072 9149296
11:29:07 20011 8192000000 10106204160 10499457024 1109194432 11.925 100 61 122 1 1207611456 85883696 12533328
11:30:31 7286 8192000000 10113360992 10516402176 1122240272 12.048 100 47 93 0 1226346976 94448176 9658528
11:31:40 11828 8192000000 10138493872 10542088192 1134730432 12.166 100 51 100 1 1245857056 100575248 10551376
11:32:56 22153 8192000000 10188062816 10593628160 1143135536 12.245 100 58 116 0 1297380112 141723712 12520864
11:34:27 10978 8192000000 10236989824 10653720576 1164225904 12.443 100 53 105 2 1355265296 179141248 11898144
11:35:41 11912 8192000000 10284561296 10698166272 1170734672 12.504 100 63 127 0 1388498528 203666912 14096944
11:36:58 5695 8192000000 10290130048 10706300928 1175173840 12.545 100 55 109 0 1393894832 206167968 12553024
11:38:10 1273 8192000000 10346214160 10752516096 1176219520 12.555 100 81 160 1 1445222992 251425072 17578400
11:39:17 6915 8192000000 10400388432 10832846848 1178839344 12.579 100 71 138 1 1495379888 301062240 15478304
11:40:36 8893 8192000000 10402844064 10830393344 1183032912 12.618 100 66 131 2 1520048912 321688064 15327936
11:41:51 3160 8192000000 10387822656 10847072256 1180380992 12.594 100 55 110 2 1495646784 302320032 12945760
11:42:57 3104 8192000000 10377476208 10797084672 1156369376 12.369 98 62 121 2 1463543376 293378320 13795680
11:44:05 8680 8192000000 10352295216 10827444224 1140181840 12.217 100 41 82 0 1504290960 353969520 10139600
11:45:19 2252 8192000000 10333632720 10738094080 1128717408 12.109 100 81 160 1 1431867152 284557104 18592640
11:46:25 6187 8192000000 10369712080 10795356160 1147956208 12.290 100 40 80 1 1479077136 320941088 10179840
11:47:33 12192 8192000000 10280048656 10715832320 1120312320 12.030 99 28 56 1 1385409680 258404048 6693312
11:48:48 28950 8192000000 10246914384 10715721728 1101890944 11.856 99 21 42 1 1336914416 229104896 5918576
11:50:18 37676 8192000000 10112547808 10621751296 1103484256 11.871 100 11 22 1 1293790864 187033632 3272976
11:52:25 118749 8192000000 10059292208 10482360320 1067256048 11.526 96 21 39 1 1107905136 36386672 4262416
11:55:40 103191 8192000000 10058204016 10534592512 1086531968 11.710 98 36 72 0 1175675152 81115520 8027664
11:58:28 18801 8192000000 10080373632 10504777728 1115542384 11.985 100 34 68 2 1188702848 65673296 7487168
11:59:50 36624 8192000000 10059641728 10507939840 1110719888 11.939 99 16 32 2 1183651568 69124016 3807664
12:01:29 30777 8192000000 10078453968 10513833984 1092882144 11.770 97 11 22 1 1171321248 75947872 2491232
12:03:03 68253 8192000000 10113832688 10583851008 1117329456 12.002 99 23 42 3 1217239440 95603184 4306800
12:05:17 58495 8192000000 10096065328 10524143616 1132652976 12.146 100 23 46 0 1207190080 69371184 5165920
12:07:28 31985 8192000000 10170731856 10607906816 1146529888 12.277 100 24 48 3 1272179056 119998224 5650944
12:09:03 54040 8192000000 10067636336 10581946368 1126570768 12.089 99 7 14 1 1195283936 67287136 1426032
12:10:58 63943 8192000000 10129754080 10561056768 1144580704 12.259 99 38 76 4 1193085104 40792880 7711520
12:13:04 49298 8192000000 10137579824 10598273024 1138289312 12.199 99 12 24 0 1208340720 67852928 2198480
12:14:54 40414 8192000000 10085775824 10500132864 1134543248 12.164 98 35 70 1 1159839584 18139328 7157008
12:16:36 100894 8192000000 10066839744 10535473152 1132094848 12.141 98 16 32 1 1168378848 32889520 3394480
12:19:29 31464 8192000000 10073838640 10549846016 1149841584 12.308 100 7 14 0 1182949312 31646944 1460784
12:21:02 76075 8192000000 10124963584 10599649280 1164568400 12.446 100 15 30 1 1229196016 61402144 3225472
12:23:22 37722 8192000000 10111293424 10569682944 1124970064 12.074 97 22 44 3 1183958192 54275936 4712192
12:25:12 59537 8192000000 10173724960 10604974080 1152127648 12.329 99 28 56 1 1228918640 70180144 6610848
12:27:16 103319 8192000000 10118996640 10641600512 1149120624 12.301 99 23 46 1 1290070064 135410848 5538592
12:30:00 77765 8192000000 10207508208 10687582208 1155605680 12.362 100 18 36 1 1240097040 80725840 3765520
12:33:31 65664 8192000000 10053213392 10475167744 1076292080 11.612 95 13 26 2 1108039584 29112448 2635056
12:35:40 87385 8192000000 10073839264 10538700800 1120497616 12.032 100 28 56 1 1200181712 74269744 5414352
12:38:14 91779 8192000000 10065158336 10572451840 1073867312 11.589 95 5 11 0 1117902256 42894192 1140752
12:40:54 56673 8192000000 10074588624 10516627456 1115510832 11.985 99 27 55 0 1161986032 40437600 6037600
12:42:51 38881 8192000000 10090721392 10525433856 1111852688 11.950 98 20 40 0 1152299152 36341360 4105104
12:44:30 61750 8192000000 10085059664 10534318080 1097652800 11.815 97 24 48 1 1131125424 28667856 4804768
12:46:33 45965 8192000000 10090355824 10504929280 1117844032 12.007 100 32 63 2 1161137248 36729392 6563824
12:48:24 81855 8192000000 10063850032 10552287232 1102710832 11.863 98 22 42 3 1176491776 69628528 4152416
12:50:46 30589 8192000000 10090460928 10502541312 1094955056 11.790 98 25 51 2 1123370656 22722096 5693504
12:52:17 140677 8192000000 10077535520 10565083136 1109223488 11.925 99 30 60 1 1206473264 90674608 6575168
12:55:42 59913 8192000000 10099272592 10585735168 1126219712 12.086 100 11 22 0 1220106192 91264352 2622128
12:57:42 73810 8192000000 10119985296 10551177216 1113736496 11.968 98 16 32 0 1185550176 68216544 3597136
13:00:06 46088 8192000000 10136158048 10578968576 1137679856 12.194 99 22 44 2 1224568368 82149152 4739360
13:01:53 77572 8192000000 10092598400 10616471552 1132414208 12.144 99 11 22 0 1182315824 47362528 2539088
13:04:19 24185 8192000000 10095082432 10528468992 1106269024 11.897 96 34 67 5 1163831744 49866016 7696704
13:05:46 83341 8192000000 10170634928 10617413632 1097660672 11.815 96 12 23 1 1214828944 114199968 2968304
13:08:38 57884 8192000000 10086813136 10530992128 1131262864 12.133 100 27 54 2 1171381984 34872912 5246208
13:10:36 76280 8192000000 10084487968 10562093056 1126988576 12.093 99 15 30 0 1181347584 51340080 3018928
13:13:33 63124 8192000000 10063650928 10553303040 1140226656 12.218 100 5 10 0 1175366784 34246896 893232
13:15:36 51643 8192000000 10058973664 10535014400 1139768976 12.213 100 7 14 1 1167236272 26138656 1328640
13:17:32 63106 8192000000 10156363104 10600636416 1129826080 12.120 99 26 52 2 1220094048 84670512 5597456
13:19:44 94895 8192000000 10083430544 10526990336 1130655824 12.128 99 14 28 1 1185876448 52070976 3149648
13:22:23 86822 8192000000 10084967200 10527641600 1067537504 11.529 94 18 35 4 1114132656 42746864 3848288
13:24:53 73857 8192000000 10079861680 10535022592 1091897328 11.761 97 17 34 0 1153053792 57944752 3211712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-01-2021 13:27:51 to 08-01-2021 13:28:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-01-2021 13:28:07 to 08-01-2021 13:28:09.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          228408
MON$OLDEST_ACTIVE               231792
MON$OLDEST_SNAPSHOT             231792
MON$NEXT_TRANSACTION            231792
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2085280
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             793
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2319 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2319 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2319 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2319 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:36: 
:37: mscs-test-161.budzdorov.ru    Fri Jan  8 13:26:58 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Fri Jan  8 13:26:58 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Fri Jan  8 13:26:58 2021
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Fri Jan  8 13:26:59 2021
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Fri Jan  8 13:26:59 2021
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:55: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

08.01.21 13:28:10. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-01-08 13:28:12 No severe PSQL-related problems occured
08.01.21 13:28:14. End of report.