Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.11.2021 13:39
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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 2021-11-11 18:28
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.33
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 16748428 kB
MemAvailable 31634844 kB
Buffers 0 kB
Cached 14976060 kB
SwapCached 0 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.1
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-11-20 13:37:05
DTS_END                         2021-11-20 13:37:05.7120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52956
TRN_ID                          235963
ATT_ID                          696
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: 9140.91 21065 1096909
customer order: creation 168.90 418 20268
customer order: refuse 14.09 322 1691
order to supplier: creation 44.86 1428 5383
order to supplier: removal 3.38 128446 406
invoice (draft): creation 44.23 3605 5308
invoice (draft): removal 52.50 1115 6300
invoice accept: apply 40.46 21177 4855
invoice accept: cancel 47.69 993 5723
customer reserve: creation 3729.88 210 447585
customer reserve: removal 4704.36 72 564523
realization accept: apply 83.02 207 9962
realization accept: cancel 80.25 138 9630
payment from customer: creation 58.08 149 6969
payment from customer: removal 5.46 109 655
payment to supplier: creation 49.40 144 5928
payment to supplier: removal 3.42 98 410
service: total inventory turnovers 3.95 3468 474
service: total monetary turnovers 6.07 547 728
service: aggregate perf. data 0.89 17462 107
service: refresh index statistics 0.03 241182 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-11-2021 13:39:58 to 20-11-2021 13:40:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8755
TEST_TIME 2 5086
TEST_TIME 3 5922
TEST_TIME 4 5636
TEST_TIME 5 6240
TEST_TIME 6 5814
TEST_TIME 7 5789
TEST_TIME 8 5551
TEST_TIME 9 5446
TEST_TIME 10 5673
TEST_TIME 11 5796
TEST_TIME 12 5894
TEST_TIME 13 5884
TEST_TIME 14 5950
TEST_TIME 15 6267
TEST_TIME 16 6357
TEST_TIME 17 6702
TEST_TIME 18 6688
TEST_TIME 19 7146
TEST_TIME 20 7494
TEST_TIME 21 7659
TEST_TIME 22 7910
TEST_TIME 23 7991
TEST_TIME 24 7974
TEST_TIME 25 7988
TEST_TIME 26 7853
TEST_TIME 27 8416
TEST_TIME 28 9265
TEST_TIME 29 10747
TEST_TIME 30 10971
TEST_TIME 31 10668
TEST_TIME 32 10542
TEST_TIME 33 10266
TEST_TIME 34 10179
TEST_TIME 35 10269
TEST_TIME 36 10087
TEST_TIME 37 10038
TEST_TIME 38 10064
TEST_TIME 39 10081
TEST_TIME 40 9931
TEST_TIME 41 9887
TEST_TIME 42 9811
TEST_TIME 43 9686
TEST_TIME 44 9723
TEST_TIME 45 9691
TEST_TIME 46 9666
TEST_TIME 47 9688
TEST_TIME 48 9645
TEST_TIME 49 9660
TEST_TIME 50 9631
TEST_TIME 51 9662
TEST_TIME 52 9651
TEST_TIME 53 9760
TEST_TIME 54 9882
TEST_TIME 55 9951
TEST_TIME 56 9897
TEST_TIME 57 9875
TEST_TIME 58 9727
TEST_TIME 59 9675
TEST_TIME 60 9576
TEST_TIME 61 9626
TEST_TIME 62 9570
TEST_TIME 63 9477
TEST_TIME 64 9431
TEST_TIME 65 9412
TEST_TIME 66 9468
TEST_TIME 67 9462
TEST_TIME 68 9478
TEST_TIME 69 9430
TEST_TIME 70 9582
TEST_TIME 71 9524
TEST_TIME 72 9498
TEST_TIME 73 9546
TEST_TIME 74 9623
TEST_TIME 75 9584
TEST_TIME 76 9647
TEST_TIME 77 9606
TEST_TIME 78 9508
TEST_TIME 79 9501
TEST_TIME 80 9455
TEST_TIME 81 9483
TEST_TIME 82 9405
TEST_TIME 83 9391
TEST_TIME 84 9371
TEST_TIME 85 9405
TEST_TIME 86 9346
TEST_TIME 87 9364
TEST_TIME 88 9322
TEST_TIME 89 9402
TEST_TIME 90 9396
TEST_TIME 91 9373
TEST_TIME 92 9419
TEST_TIME 93 9391
TEST_TIME 94 9404
TEST_TIME 95 9403
TEST_TIME 96 9389
TEST_TIME 97 9490
TEST_TIME 98 9540
TEST_TIME 99 9447
TEST_TIME 100 9393
TEST_TIME 101 9374
TEST_TIME 102 9338
TEST_TIME 103 9281
TEST_TIME 104 9219
TEST_TIME 105 9202
TEST_TIME 106 9193
TEST_TIME 107 9194
TEST_TIME 108 9179
TEST_TIME 109 9155
TEST_TIME 110 9174
TEST_TIME 111 9174
TEST_TIME 112 9182
TEST_TIME 113 9153
TEST_TIME 114 9177
TEST_TIME 115 9196
TEST_TIME 116 9161
TEST_TIME 117 9175
TEST_TIME 118 9291
TEST_TIME 119 9221
TEST_TIME 120 9148

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-11-2021 13:40:02 to 20-11-2021 13:40:05.

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 1512598 1512598 0 0.00 0 24501 8 0 0
doc_list_biud 1512597 1512597 0 0.00 0 88086 34 0 0
sp_add_invoice_to_stock 5038 4855 183 3.63 31 248549 21178 0 183
sp_cancel_adding_invoice 5723 5723 0 0.00 4 75567 993 0 0
sp_cancel_client_order 1691 1691 0 0.00 4 19547 322 0 0
sp_cancel_customer_reserve 564523 564523 0 0.00 1 52514 73 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 9820 109 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 9854 98 0 0
sp_cancel_supplier_invoice 6311 6300 11 0.17 2 88094 1116 0 11
sp_cancel_supplier_order 406 406 0 0.00 12 1279789 128447 0 0
sp_cancel_write_off 9630 9630 0 0.00 1 33359 139 0 0
sp_client_order 20268 20268 0 0.00 4 46501 419 0 0
sp_customer_reserve 447585 447585 0 0.00 0 67806 211 0 0
sp_fill_shopping_cart 30955 30955 0 0.00 1 24101 47 0 0
sp_fill_shopping_cart_clo_res 447577 447577 0 0.00 0 19162 18 0 0
sp_get_clo_for_invoice 4845 4845 0 0.00 0 30625 400 0 0
sp_kill_cost_storno 16418 16418 0 0.00 0 9814 64 0 0
sp_kill_qstorno_ret_qs2qd 1145382 1145382 0 0.00 0 88083 40 0 0
sp_kill_qty_storno 603080 603080 0 0.00 0 88085 85 0 0
sp_lock_selected_doc 20445 20445 0 0.00 0 8723 7 0 0
sp_make_cost_storno 27704 27704 0 0.00 0 24255 63 0 0
sp_multiply_rows_for_pdistr 27704 27704 0 0.00 0 24292 35 0 0
sp_multiply_rows_for_qdistr 20268 20268 0 0.00 0 46493 221 0 0
sp_pay_from_customer 6969 6969 0 0.00 4 29391 150 0 0
sp_pay_to_supplier 5928 5928 0 0.00 5 24462 144 0 0
sp_payment_common 12897 12897 0 0.00 1 29128 34 0 0
sp_qd_handle_on_cancel_clo 1691 1691 0 0.00 0 19538 277 0 0
sp_qd_handle_on_invoice_upd_sts 10568 10568 0 0.00 0 28661 388 0 0
sp_qd_handle_on_reserve_upd_sts 9962 9962 0 0.00 0 18518 61 0 0
sp_reserve_write_off 9962 9962 0 0.00 7 24528 207 0 0
sp_supplier_invoice 5308 5308 0 0.00 6 108614 3605 0 0
sp_supplier_order 5383 5383 0 0.00 5 46176 1428 0 0
srv_aggregate_perf_data 107 107 0 0.00 2817 371662 17462 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1799 118942 42251 0 0
srv_find_qd_qs_mism 1050861 1050861 0 0.00 0 15065 8 0 0
srv_make_invnt_saldo 474 474 0 0.00 59 99625 3469 0 0
srv_make_money_saldo 728 728 0 0.00 1 8593 547 0 0
srv_recalc_idx_stat 4 4 0 0.00 167917 329413 241182 0 0
t$perf-abend:sp_add_invoice_to_stock 183 183 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4855 4855 0 0.00 0 14943 177 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 4467 122 0 0
t$perf-norm:sp_cancel_client_order 1691 1691 0 0.00 0 681 10 0 0
t$perf-norm:sp_cancel_customer_reserve 734 734 0 0.00 0 1709 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 9938 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 2247 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 2051 31 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 21604 1667 0 0
t$perf-norm:sp_cancel_write_off 941 941 0 0.00 0 2880 11 0 0
t$perf-norm:sp_client_order 20268 20268 0 0.00 0 11039 19 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 1945 7 0 0
t$perf-norm:sp_pay_from_customer 6969 6969 0 0.00 0 11651 13 0 0
t$perf-norm:sp_pay_to_supplier 5928 5928 0 0.00 0 4813 13 0 0
t$perf-norm:sp_reserve_write_off 9962 9962 0 0.00 0 4609 11 0 0
t$perf-norm:sp_supplier_invoice 5308 5308 0 0.00 0 6173 22 0 0
t$perf-norm:sp_supplier_order 5383 5383 0 0.00 0 4994 16 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 132 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 73 2 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 2856 14 0 0
t$perf-norm:srv_make_money_saldo 728 728 0 0.00 0 2967 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10052 9686 366 3.64 0 15959 26 0 366
v_all_customers 36748 36748 0 0.00 0 115 1 0 0
v_all_suppliers 30266 30266 0 0.00 0 114 1 0 0
v_all_wares 40534 40534 0 0.00 0 133 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 447 6 0 0
v_cancel_client_order 3382 3382 0 0.00 0 8590 12 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 3000 7 0 0
v_cancel_customer_reserve 1468 1468 0 0.00 0 5360 8 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 2598 9 0 0
v_cancel_supplier_invoice 800 778 22 2.75 0 15751 36 0 22
v_cancel_supplier_order 812 812 0 0.00 0 588 4 0 0
v_cancel_write_off 1882 1882 0 0.00 0 14417 27 0 0
v_max_id_clo_ord 5383 5383 0 0.00 0 1718 2 0 0
v_max_id_clo_res 996 996 0 0.00 0 2468 14 0 0
v_max_id_ord_sup 5305 5305 0 0.00 0 10677 9 0 0
v_max_non_paid_invoice 1483 1483 0 0.00 0 318 2 0 0
v_max_non_paid_realizn 3718 3718 0 0.00 0 3317 5 0 0
v_min_id_clo_ord 5383 5383 0 0.00 0 4840 7 0 0
v_min_id_clo_res 996 996 0 0.00 0 1623 6 0 0
v_min_id_ord_sup 5305 5305 0 0.00 0 12753 19 0 0
v_min_non_paid_invoice 5928 5928 0 0.00 0 24326 29 0 0
v_min_non_paid_realizn 6969 6969 0 0.00 0 29112 25 0 0
v_our_firm 10288 10288 0 0.00 0 32 1 0 0
v_reserve_write_off 19922 19922 0 0.00 0 17325 11 0 0
x_lock_depdocs_on_canc_invoice 11634 11634 0 0.00 0 13652 122 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 13452 327 0 0
x_make_qstorno_1000_1200 5383 5383 0 0.00 2 46128 1349 0 0
x_make_qstorno_1200_2000 5308 5308 0 0.00 3 97453 3415 0 0
x_make_qstorno_2100_3300 447585 447585 0 0.00 0 67747 190 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-11-2021 13:40:06 to 20-11-2021 13:40:10.

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
11:07:09 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:09 27073 8192000000 9717208688 10009845760 789128992 8.786 100 88 176 0 818367728 7723312 21515424
11:09:41 25161 8192000000 9754112224 10058407936 826457120 9.164 100 92 184 0 861666352 13055040 22154192
11:11:24 15026 8192000000 9793341360 10114482176 861808880 9.518 100 82 164 1 907830352 26613648 19407824
11:12:43 17360 8192000000 9853481376 10188611584 913208048 10.029 100 76 152 0 960683936 30452816 17023072
11:14:04 7964 8192000000 9943434960 10290110464 964110048 10.529 100 77 154 0 1047024928 67008576 15906304
11:15:13 3942 8192000000 9993934464 10353840128 1000751040 10.886 100 78 156 1 1102275296 85630752 15893504
11:16:18 3423 8192000000 9974856768 10339528704 1021581328 11.087 100 76 150 2 1089022720 51667952 15773440
11:17:23 1877 8192000000 10022038752 10387156992 1050131312 11.362 100 85 170 1 1130527392 63273856 17122224
11:18:26 2929 8192000000 10035571552 10407931904 1070476656 11.557 100 87 174 1 1150820208 62137104 18206448
11:19:32 1596 8192000000 10013663344 10395987968 1076236544 11.612 100 59 115 3 1132607168 44003728 12366896
11:20:40 5037 8192000000 9998963584 10392784896 1082728928 11.673 100 40 80 0 1109238304 18231376 8278000
11:21:47 5914 8192000000 9996549984 10391232512 1089668368 11.740 100 30 61 0 1124804416 28545904 6590144
11:22:53 3740 8192000000 10014362208 10408460288 1097553152 11.814 100 45 89 3 1134020656 26056064 10411440
11:23:59 25251 8192000000 10035040048 10419089408 1097868512 11.817 100 77 154 1 1136710928 22170224 16672192
11:25:24 6136 8192000000 10021850240 10425262080 1107783936 11.911 100 33 66 0 1136119264 21658480 6676848
11:26:34 8566 8192000000 10007575920 10410409984 1098899920 11.827 100 30 61 0 1125340192 20265712 6174560
11:27:45 22326 8192000000 9981086336 10394312704 1035009504 11.217 95 33 64 3 1052149024 10462240 6677280
11:29:42 4865 8192000000 9968392800 10343206912 1019946992 11.072 98 69 139 0 1059228752 24823536 14458224
11:30:48 5439 8192000000 9935104400 10314244096 1000349952 10.882 98 43 85 2 1051013664 41912672 8751040
11:31:53 5986 8192000000 9942962336 10323681280 1009016576 10.966 99 49 99 1 1034126864 13164672 11945616
11:33:03 28563 8192000000 9970746848 10366754816 1024228432 11.113 99 30 59 1 1061555664 31086016 6241216
11:34:33 10613 8192000000 9967538304 10368471040 1053183968 11.391 100 34 68 0 1077031904 15988256 7859680
11:35:44 5052 8192000000 9988364400 10392002560 1067640624 11.530 100 29 58 1 1098600384 24786512 6173248
11:36:57 6694 8192000000 9987135808 10386075648 1075689360 11.606 100 34 66 3 1100139648 17179712 7270576
11:38:12 76275 8192000000 10046368320 10448670720 1104646160 11.882 100 34 67 2 1139411232 27299312 7465760
11:40:40 23197 8192000000 10063467008 10478510080 1117091920 12.000 100 37 74 0 1163512656 38776688 7644048
11:42:30 12372 8192000000 10102106672 10521214976 1144377664 12.257 100 53 106 1 1215585088 60385168 10822256
11:43:49 4584 8192000000 10157369744 10559565824 1156012640 12.366 100 59 118 1 1263845120 95109264 12723216
11:44:57 28917 8192000000 10185942000 10597498880 1147530080 12.286 99 50 97 2 1280908304 122917616 10460608
11:46:55 8520 8192000000 10219900864 10630430720 1166801904 12.467 100 61 120 1 1320626240 140160032 13664304
11:48:12 6711 8192000000 10258343088 10682015744 1182279936 12.611 100 53 106 2 1363949312 169533728 12135648
11:49:28 3929 8192000000 10284789760 10692993024 1167678240 12.475 99 69 138 0 1379569600 195929440 15961920
11:50:38 5221 8192000000 10277884848 10684559360 1164121040 12.442 99 58 116 0 1379282832 202257280 12904512
11:51:47 1796 8192000000 10342335648 10739240960 1174202848 12.536 100 83 165 1 1437865888 244248384 19414656
11:52:55 2308 8192000000 10386112080 10786967552 1166300096 12.462 99 88 177 0 1474715424 288054880 20360448
11:54:03 7199 8192000000 10433536736 10854170624 1171162896 12.508 99 66 130 1 1532991664 346244560 15584208
11:55:16 2765 8192000000 10439746304 10840141824 1162981136 12.431 100 83 166 0 1535992640 353659072 19352432
11:56:25 2794 8192000000 10459948256 10855108608 1157636336 12.381 100 88 175 1 1555998192 377919280 20442576
11:57:33 3531 8192000000 10460601664 10864586752 1164723088 12.447 100 78 156 1 1562438736 379152608 18563040
11:58:41 2943 8192000000 10479878704 10885341184 1163325696 12.434 100 59 118 0 1572816016 394350400 15139920
11:59:55 3625 8192000000 10456940624 10905243648 1150443552 12.314 99 50 99 0 1547298704 384248144 12607008
12:01:06 7484 8192000000 10502466816 10936471552 1165690336 12.457 100 41 82 0 1594986352 418848688 10447328
12:02:36 20086 8192000000 10546284016 11003326464 1142809088 12.242 100 35 70 0 1675060000 522110704 10140208
12:04:05 16487 8192000000 10618196144 11066458112 1125303344 12.077 100 38 75 2 1708684272 572551056 10829872
12:05:37 17440 8192000000 10373640384 10970206208 1109406384 11.927 100 25 50 0 1552627408 435595664 7625360
12:07:09 33369 8192000000 10061834288 10503708672 1104435904 11.880 99 14 28 0 1168553920 61467504 2650512
12:08:44 80876 8192000000 10127639600 10587803648 1130439664 12.126 100 55 110 0 1203769664 61909568 11420432
12:11:34 63068 8192000000 10106601744 10574442496 1132255952 12.143 99 19 38 1 1208305120 71988320 4060848
12:13:39 24325 8192000000 10113049376 10555002880 1136358176 12.181 99 27 54 0 1207241280 64618656 6264448
12:15:05 54739 8192000000 10100763104 10575605760 1138197568 12.199 99 23 46 0 1228518608 85314240 5006800
12:17:52 60747 8192000000 10105195104 10587262976 1158967728 12.394 100 16 32 0 1200489056 38692032 2829296
12:20:46 56165 8192000000 10111622416 10561597440 1136622944 12.184 98 19 38 0 1205288608 64448272 4217392
12:22:52 68182 8192000000 10099118624 10575654912 1148607968 12.296 99 17 33 2 1196295392 44243664 3443760
12:25:00 77994 8192000000 10103560320 10561486848 1115456160 11.984 96 16 33 1 1170699120 51909504 3333456
12:27:23 22726 8192000000 10125750832 10556301312 1155797824 12.364 100 35 70 1 1204307856 41529632 6980400
12:28:48 51322 8192000000 10141917280 10594226176 1141697344 12.231 98 25 50 0 1262464208 115297264 5469600
12:30:39 69618 8192000000 10126047168 10626023424 1137584256 12.193 97 13 26 1 1261818608 121271952 2962400
12:33:22 56045 8192000000 10101578784 10555813888 1101964160 11.856 95 26 52 1 1155436448 48234640 5237648
12:35:26 64574 8192000000 10099752432 10531053568 1135137280 12.170 99 34 68 1 1172534576 30413712 6983584
12:37:33 23741 8192000000 10125578608 10554953728 1147975392 12.290 100 44 87 0 1218134480 61821040 8338048
12:39:04 66026 8192000000 10064820000 10513752064 1113328640 11.964 96 11 22 0 1140175856 25034592 1812624
12:41:10 45736 8192000000 10102840464 10554847232 1123160736 12.057 97 12 24 0 1198375136 72164144 3050256
12:43:05 69872 8192000000 10077138032 10574422016 1129076736 12.113 98 12 24 0 1179845248 48595536 2172976
12:45:15 84903 8192000000 10119407520 10554945536 1111476320 11.946 97 24 48 0 1184527952 68105408 4946224
12:47:40 58312 8192000000 10094506704 10547580928 1114433936 11.974 97 16 32 2 1166989632 49259264 3296432
12:49:47 53366 8192000000 10113842848 10602790912 1112686704 11.958 98 14 28 0 1195490640 79814128 2989808
12:51:44 39769 8192000000 10056844720 10476204032 1090943408 11.752 96 31 60 0 1139554560 42405136 6206016
12:53:25 40672 8192000000 10074004608 10539036672 1099964384 11.837 98 25 50 0 1152851536 48314976 4572176
12:55:09 118942 8192000000 10123352304 10568495104 1083173824 11.678 96 34 68 0 1152014928 61810176 7030928
12:58:15 59832 8192000000 10085689344 10570883072 1125976816 12.083 100 10 20 0 1196844912 68225888 2642208
13:00:23 65990 8192000000 10054483552 10514964480 1094635040 11.787 97 16 28 1 1153279168 55156752 3487376
13:02:29 77979 8192000000 10077832592 10530500608 1090674752 11.749 97 7 14 0 1142122512 50027600 1420160
13:05:08 44158 8192000000 10133452032 10567716864 1117699424 12.005 99 21 42 0 1193050368 70564400 4786544
13:07:02 51837 8192000000 10094375408 10537746432 1117191184 12.000 98 24 48 0 1161821424 39884304 4745936
13:09:11 29294 8192000000 10097742512 10536386560 1128127296 12.104 98 41 82 0 1195988832 59805296 8056240
13:10:42 15920 8192000000 10104232656 10533040128 1138985008 12.206 99 51 101 1 1190904432 41473744 10445680
13:12:11 34090 8192000000 10101596256 10553839616 1151260400 12.321 100 34 68 0 1197904000 39586336 7057264
13:13:52 69706 8192000000 10161505056 10678059008 1110835888 11.940 97 23 46 1 1233630368 118001248 4793232
13:16:09 80555 8192000000 10040465888 10476220416 1116728496 11.996 98 8 16 0 1153789024 35647280 1413248
13:18:30 48982 8192000000 10062012992 10518245376 1144080368 12.254 100 14 28 0 1183566608 36679728 2806512
13:21:22 72240 8192000000 10092772080 10548314112 1115825824 11.988 98 22 44 1 1161294688 40421648 5047216
13:23:47 41820 8192000000 10089211440 10555539456 1137488048 12.192 100 24 48 1 1183852192 41222048 5142096
13:25:31 44771 8192000000 10081607744 10526089216 1129905488 12.120 99 29 57 0 1176083280 40012496 6165296
13:27:17 70298 8192000000 10097410432 10550579200 1135262272 12.171 99 26 52 0 1186863328 45908656 5692400
13:29:38 41956 8192000000 10121728304 10559328256 1098094800 11.820 97 39 76 0 1174689424 68707392 7887232
13:31:24 62013 8192000000 10077480592 10537140224 1098297040 11.821 97 38 76 2 1180041168 73971936 7772192
13:33:28 32922 8192000000 10161408208 10585612288 1100038848 11.838 97 40 80 0 1204294400 96165824 8089728
13:35:03 116361 8192000000 10059749200 10534440960 1072196464 11.573 95 16 32 0 1130621712 54909584 3515664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-11-2021 13:40:12 to 20-11-2021 13:40:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 183 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 20-11-2021 13:40:28 to 20-11-2021 13:40:29.

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          235449
MON$OLDEST_ACTIVE               236286
MON$OLDEST_SNAPSHOT             236286
MON$NEXT_TRANSACTION            236286
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                       2047968
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             802
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:80: 
:81: mscs-test-161.budzdorov.ru    Sat Nov 20 13:37:14 2021
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Sat Nov 20 13:37:43 2021
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:87: 
--- end of diff output ---

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

20.11.21 13:40:30. 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-11-20 13:40:33 No severe PSQL-related problems occured
20.11.21 13:40:34. End of report.