Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.06.2022 07: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 16110464 kB
MemAvailable 31373812 kB
Buffers 0 kB
Cached 15624808 kB
SwapCached 2448 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.2
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 2022-06-04 07:37:16
DTS_END                         2022-06-04 07:37:16.4640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48696
TRN_ID                          224780
ATT_ID                          644
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: 9018.83 28643 1082260
customer order: creation 167.44 438 20093
customer order: refuse 15.14 419 1817
order to supplier: creation 45.13 1378 5416
order to supplier: removal 3.43 157339 412
invoice (draft): creation 46.36 3366 5563
invoice (draft): removal 49.48 1027 5937
invoice accept: apply 42.06 26607 5047
invoice accept: cancel 44.56 1282 5347
customer reserve: creation 3916.27 253 469952
customer reserve: removal 4391.52 96 526982
realization accept: apply 82.07 270 9848
realization accept: cancel 85.38 166 10245
payment from customer: creation 59.70 172 7164
payment from customer: removal 5.85 120 702
payment to supplier: creation 48.90 197 5868
payment to supplier: removal 3.38 138 406
service: total inventory turnovers 3.68 4653 442
service: total monetary turnovers 7.71 412 925
service: aggregate perf. data 0.74 22091 89
service: refresh index statistics 0.04 352441 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-06-2022 07:40:03 to 04-06-2022 07:40:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9415
TEST_TIME 2 7948
TEST_TIME 3 7535
TEST_TIME 4 7900
TEST_TIME 5 8068
TEST_TIME 6 8070
TEST_TIME 7 7652
TEST_TIME 8 7677
TEST_TIME 9 7199
TEST_TIME 10 7217
TEST_TIME 11 7010
TEST_TIME 12 7279
TEST_TIME 13 7178
TEST_TIME 14 7264
TEST_TIME 15 7331
TEST_TIME 16 7223
TEST_TIME 17 7366
TEST_TIME 18 7457
TEST_TIME 19 7474
TEST_TIME 20 7700
TEST_TIME 21 7747
TEST_TIME 22 8097
TEST_TIME 23 8164
TEST_TIME 24 8437
TEST_TIME 25 8449
TEST_TIME 26 8818
TEST_TIME 27 9021
TEST_TIME 28 9426
TEST_TIME 29 9399
TEST_TIME 30 9438
TEST_TIME 31 9564
TEST_TIME 32 10195
TEST_TIME 33 10771
TEST_TIME 34 10918
TEST_TIME 35 10925
TEST_TIME 36 10851
TEST_TIME 37 11020
TEST_TIME 38 11112
TEST_TIME 39 10899
TEST_TIME 40 10741
TEST_TIME 41 10698
TEST_TIME 42 10617
TEST_TIME 43 10529
TEST_TIME 44 10373
TEST_TIME 45 10351
TEST_TIME 46 10256
TEST_TIME 47 10291
TEST_TIME 48 10292
TEST_TIME 49 10320
TEST_TIME 50 10221
TEST_TIME 51 10162
TEST_TIME 52 10043
TEST_TIME 53 10031
TEST_TIME 54 10019
TEST_TIME 55 10040
TEST_TIME 56 9961
TEST_TIME 57 9857
TEST_TIME 58 9853
TEST_TIME 59 9851
TEST_TIME 60 9832
TEST_TIME 61 9867
TEST_TIME 62 9996
TEST_TIME 63 10009
TEST_TIME 64 10013
TEST_TIME 65 10111
TEST_TIME 66 10013
TEST_TIME 67 9886
TEST_TIME 68 9913
TEST_TIME 69 9881
TEST_TIME 70 9837
TEST_TIME 71 9839
TEST_TIME 72 9777
TEST_TIME 73 9726
TEST_TIME 74 9730
TEST_TIME 75 9667
TEST_TIME 76 9596
TEST_TIME 77 9586
TEST_TIME 78 9538
TEST_TIME 79 9485
TEST_TIME 80 9481
TEST_TIME 81 9433
TEST_TIME 82 9418
TEST_TIME 83 9408
TEST_TIME 84 9441
TEST_TIME 85 9426
TEST_TIME 86 9469
TEST_TIME 87 9449
TEST_TIME 88 9416
TEST_TIME 89 9439
TEST_TIME 90 9493
TEST_TIME 91 9559
TEST_TIME 92 9523
TEST_TIME 93 9521
TEST_TIME 94 9523
TEST_TIME 95 9447
TEST_TIME 96 9379
TEST_TIME 97 9352
TEST_TIME 98 9354
TEST_TIME 99 9316
TEST_TIME 100 9281
TEST_TIME 101 9273
TEST_TIME 102 9247
TEST_TIME 103 9221
TEST_TIME 104 9207
TEST_TIME 105 9225
TEST_TIME 106 9206
TEST_TIME 107 9240
TEST_TIME 108 9199
TEST_TIME 109 9173
TEST_TIME 110 9256
TEST_TIME 111 9288
TEST_TIME 112 9286
TEST_TIME 113 9287
TEST_TIME 114 9299
TEST_TIME 115 9242
TEST_TIME 116 9193
TEST_TIME 117 9199
TEST_TIME 118 9160
TEST_TIME 119 9119
TEST_TIME 120 9050

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-06-2022 07:40:06 to 04-06-2022 07:40:10.

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 1514381 1514381 0 0.00 0 29266 14 0 0
doc_list_biud 1514373 1514373 0 0.00 0 37754 39 0 0
sp_add_invoice_to_stock 5131 5047 84 1.63 40 191702 26608 0 84
sp_cancel_adding_invoice 5347 5347 0 0.00 6 74912 1283 0 0
sp_cancel_client_order 1817 1817 0 0.00 6 23209 419 0 0
sp_cancel_customer_reserve 526982 526982 0 0.00 1 36468 96 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 4 8530 120 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 4 4767 138 0 0
sp_cancel_supplier_invoice 5941 5937 4 0.06 3 26018 1027 0 4
sp_cancel_supplier_order 412 412 0 0.00 9 1373590 157339 0 0
sp_cancel_write_off 10245 10245 0 0.00 2 8066 167 0 0
sp_client_order 20093 20093 0 0.00 6 31202 438 0 0
sp_customer_reserve 469952 469952 0 0.00 1 83543 254 0 0
sp_fill_shopping_cart 31062 31062 0 0.00 2 14375 83 0 0
sp_fill_shopping_cart_clo_res 469922 469922 0 0.00 0 18972 18 0 0
sp_get_clo_for_invoice 5002 5002 0 0.00 0 14738 268 0 0
sp_kill_cost_storno 16700 16700 0 0.00 0 29252 73 0 0
sp_kill_qstorno_ret_qs2qd 1070558 1070558 0 0.00 0 36382 42 0 0
sp_kill_qty_storno 565588 565588 0 0.00 0 37752 97 0 0
sp_lock_selected_doc 20771 20771 0 0.00 0 6336 7 0 0
sp_make_cost_storno 27882 27882 0 0.00 0 17817 82 0 0
sp_multiply_rows_for_pdistr 27881 27881 0 0.00 0 6367 37 0 0
sp_multiply_rows_for_qdistr 20093 20093 0 0.00 0 8459 159 0 0
sp_pay_from_customer 7164 7164 0 0.00 5 12623 172 0 0
sp_pay_to_supplier 5868 5868 0 0.00 6 13342 198 0 0
sp_payment_common 13032 13032 0 0.00 2 7507 46 0 0
sp_qd_handle_on_cancel_clo 1817 1817 0 0.00 1 23166 336 0 0
sp_qd_handle_on_invoice_upd_sts 10349 10349 0 0.00 0 37751 525 0 0
sp_qd_handle_on_reserve_upd_sts 9846 9846 0 0.00 0 15094 61 0 0
sp_reserve_write_off 9848 9848 0 0.00 9 17962 270 0 0
sp_supplier_invoice 5563 5563 0 0.00 10 106976 3367 0 0
sp_supplier_order 5416 5416 0 0.00 7 53339 1378 0 0
srv_aggregate_perf_data 89 89 0 0.00 2914 362283 22091 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 632 170501 37551 0 0
srv_find_qd_qs_mism 1032923 1032923 0 0.00 0 17831 11 0 0
srv_make_invnt_saldo 442 442 0 0.00 87 128991 4654 0 0
srv_make_money_saldo 925 925 0 0.00 1 18128 413 0 0
srv_recalc_idx_stat 5 5 0 0.00 242582 480455 352441 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5051 5051 0 0.00 1 13887 901 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 2 5799 481 0 0
t$perf-norm:sp_cancel_client_order 1817 1817 0 0.00 0 24833 41 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 5200 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 2186 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 4396 28 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 3059 33 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 75296 6847 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 9606 32 0 0
t$perf-norm:sp_client_order 20093 20093 0 0.00 0 26994 40 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 542 13 0 0
t$perf-norm:sp_pay_from_customer 7164 7164 0 0.00 0 14255 20 0 0
t$perf-norm:sp_pay_to_supplier 5869 5869 0 0.00 0 14250 18 0 0
t$perf-norm:sp_reserve_write_off 9848 9848 0 0.00 0 12601 24 0 0
t$perf-norm:sp_supplier_invoice 5563 5563 0 0.00 0 4759 33 0 0
t$perf-norm:sp_supplier_order 5416 5416 0 0.00 0 3505 30 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 731 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 141 8 0 0
t$perf-norm:srv_make_invnt_saldo 442 442 0 0.00 0 1219 10 0 0
t$perf-norm:srv_make_money_saldo 925 925 0 0.00 0 588 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 195 95 0 0
v_add_invoice_to_stock 10162 9994 168 1.65 0 8624 25 0 168
v_all_customers 37250 37250 0 0.00 0 256 3 0 0
v_all_suppliers 30828 30828 0 0.00 0 256 4 0 0
v_all_wares 40178 40178 0 0.00 0 145 4 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 1905 19 0 0
v_cancel_client_order 3634 3634 0 0.00 0 6716 14 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 4431 15 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 1539 11 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 3209 16 0 0
v_cancel_supplier_invoice 890 882 8 0.89 0 1940 22 0 8
v_cancel_supplier_order 824 824 0 0.00 0 1849 12 0 0
v_cancel_write_off 2052 2052 0 0.00 0 2874 24 0 0
v_max_id_clo_ord 5410 5410 0 0.00 0 1444 5 0 0
v_max_id_clo_res 956 956 0 0.00 0 4588 24 0 0
v_max_id_ord_sup 5560 5560 0 0.00 0 2876 6 0 0
v_max_non_paid_invoice 1424 1424 0 0.00 0 126 7 0 0
v_max_non_paid_realizn 3534 3534 0 0.00 0 2503 8 0 0
v_min_id_clo_ord 5410 5410 0 0.00 0 2777 9 0 0
v_min_id_clo_res 956 956 0 0.00 0 5131 13 0 0
v_min_id_ord_sup 5560 5560 0 0.00 0 8318 12 0 0
v_min_non_paid_invoice 5868 5868 0 0.00 0 6427 17 0 0
v_min_non_paid_realizn 7163 7163 0 0.00 0 7437 19 0 0
v_our_firm 10184 10184 0 0.00 0 258 4 0 0
v_reserve_write_off 19691 19691 0 0.00 0 5679 10 0 0
x_lock_depdocs_on_canc_invoice 10843 10843 0 0.00 0 30235 144 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 19522 403 0 0
x_make_qstorno_1000_1200 5416 5416 0 0.00 3 53293 1213 0 0
x_make_qstorno_1200_2000 5563 5563 0 0.00 4 106117 3134 0 0
x_make_qstorno_2100_3300 469951 469951 0 0.00 0 83540 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-06-2022 07:40:11 to 04-06-2022 07:40:15.

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
05:07:20 5 8192000000 8800412512 8812158976 5260816 0.064 2 1 2 0 5360240 63648 35776
05:08:21 15579 8192000000 9721919664 10018689024 806470800 8.962 100 91 182 0 835956928 7791952 21694176
05:09:40 26006 8192000000 9752705888 10055602176 819681888 9.095 100 88 175 1 853413616 12467504 21264224
05:11:10 18983 8192000000 9793415504 10107375616 849440272 9.394 100 79 158 0 890287104 21988944 18857888
05:12:34 8615 8192000000 9859498496 10189258752 898852224 9.887 100 85 170 0 968694288 50966064 18876000
05:13:47 2616 8192000000 9904911584 10247360512 941936960 10.312 100 89 173 0 1025253520 64155680 19160880
05:14:53 2358 8192000000 9943172064 10294943744 986270416 10.745 100 84 162 1 1061059600 57870816 16918368
05:15:57 3765 8192000000 9969676592 10332459008 1015214928 11.026 100 83 163 0 1092941184 61292960 16433296
05:17:02 915 8192000000 10015820304 10390040576 1044662656 11.309 100 96 181 0 1138089248 75375296 18051296
05:18:04 888 8192000000 10014008992 10387763200 1052481456 11.384 100 96 189 0 1135215504 63471904 19262144
05:19:06 3428 8192000000 10020405600 10392231936 1062066560 11.476 100 87 165 1 1145924144 68348144 15509440
05:20:11 787 8192000000 10072076704 10452393984 1069721840 11.549 100 97 192 0 1193176368 104817376 18637152
05:21:14 1203 8192000000 10035461152 10421968896 1073226576 11.583 100 79 156 1 1156357728 66955104 16176048
05:22:16 920 8192000000 10049267424 10435821568 1077562144 11.624 100 95 184 2 1169300192 73963360 17774688
05:23:17 6981 8192000000 10032394960 10428440576 1083455968 11.680 100 61 122 1 1163391088 68287536 11647584
05:24:25 1623 8192000000 10062430624 10455736320 1090385104 11.746 100 88 173 3 1184840432 77575936 16879392
05:25:27 1177 8192000000 10066858640 10455502848 1095854096 11.798 100 96 181 2 1184008896 70075296 18079504
05:26:29 1185 8192000000 10060523296 10448441344 1094759136 11.788 100 95 181 1 1181974368 68406384 18808848
05:27:31 2978 8192000000 10059399920 10449055744 1091545520 11.757 100 72 139 1 1182286080 76985232 13755328
05:28:35 1208 8192000000 10069354208 10460704768 1093862880 11.779 100 94 176 0 1193479840 80767504 18849456
05:29:38 1177 8192000000 10018166704 10399199232 1074147360 11.592 99 96 179 0 1139585840 46889824 18548656
05:30:40 4410 8192000000 10035333056 10414211072 1065039184 11.505 100 74 144 1 1165599184 85417088 15142912
05:31:46 1092 8192000000 10015540704 10392260608 1052888464 11.388 100 98 185 1 1137449536 66292288 18268784
05:32:48 3483 8192000000 9975027104 10348888064 1042409024 11.288 100 79 143 3 1094694112 39072176 13212912
05:33:52 1245 8192000000 9989238352 10357202944 1021877984 11.090 99 93 180 1 1109976368 68217952 19880432
05:34:53 1326 8192000000 10013500464 10385203200 1037766368 11.243 100 96 179 0 1138264672 81279952 19218352
05:35:56 972 8192000000 9989107536 10361143296 1040948416 11.274 100 89 173 1 1114357376 55088720 18320240
05:36:57 3841 8192000000 10009585008 10397618176 1056520240 11.423 100 69 127 3 1131820288 62649232 12650816
05:38:13 2845 8192000000 10020072192 10402230272 1074880576 11.599 100 77 140 0 1147230016 58795536 13553904
05:39:23 11545 8192000000 10015994000 10406367232 1062349920 11.479 99 54 103 1 1134600128 62440208 9810000
05:40:39 11035 8192000000 10076198720 10467168256 1093478352 11.776 100 70 137 0 1197468096 90063936 13925808
05:41:56 2781 8192000000 10092700000 10482315264 1110207264 11.934 100 81 150 1 1203629808 77912400 15510144
05:43:02 807 8192000000 10119400784 10518749184 1120324336 12.030 100 90 173 1 1232047808 94498608 17224864
05:44:06 5975 8192000000 10134706976 10530476032 1124729904 12.072 100 68 128 1 1248796864 110450288 13616672
05:45:15 19840 8192000000 10187944992 10597769216 1134794384 12.167 100 79 152 2 1290920336 139423648 16702304
05:46:41 4940 8192000000 10201158448 10615738368 1149694352 12.307 100 76 150 0 1308817168 142217424 16905392
05:47:57 3885 8192000000 10213110720 10619469824 1160615888 12.409 100 60 115 1 1328390304 155976848 11797568
05:49:06 1884 8192000000 10271526240 10678939648 1176667936 12.559 100 84 163 0 1389880848 194830848 18382064
05:50:12 2509 8192000000 10307679792 10705973248 1173923392 12.533 100 89 175 1 1418265392 224929616 19412384
05:51:18 1745 8192000000 10293526160 10690154496 1179723232 12.588 100 95 178 1 1404524976 203743712 21058032
05:52:24 1963 8192000000 10340991248 10740543488 1182199968 12.611 100 94 180 1 1449433648 247436624 19797056
05:53:30 1744 8192000000 10378501600 10791460864 1199900016 12.775 100 92 176 3 1490725248 270221392 20603840
05:54:35 2108 8192000000 10385328736 10787282944 1183097904 12.619 100 96 191 1 1484517360 279603008 21816448
05:55:39 922 8192000000 10435810080 10847428608 1189133104 12.675 100 91 180 1 1540192432 328851936 22207392
05:56:42 1185 8192000000 10483280464 10909437952 1189444224 12.678 100 95 179 2 1588564768 377752416 21368128
05:57:46 1699 8192000000 10503732624 10914545664 1199104960 12.768 100 94 184 0 1604782032 384230272 21446800
05:58:50 4865 8192000000 10506301680 10953039872 1190745504 12.690 100 69 138 0 1618076816 409852208 17479104
05:59:58 1539 8192000000 10488400464 10915610624 1190830000 12.691 100 78 154 0 1594592912 384334144 19428768
06:01:02 7398 8192000000 10474671520 10943266816 1179959120 12.590 100 72 144 0 1592324032 394584192 17780720
06:02:17 750 8192000000 10455362064 10913685504 1179447376 12.585 100 91 170 1 1559653472 358901984 21304112
06:03:20 627 8192000000 10506783328 10947289088 1173945232 12.534 99 83 160 0 1612154416 419028624 19180560
06:04:27 6658 8192000000 10448847104 10903785472 1178290528 12.574 100 56 108 1 1544683856 352279376 14113952
06:05:44 8228 8192000000 10392670416 10873176064 1178511312 12.576 100 51 103 0 1497655600 306201840 12942448
06:06:57 19238 8192000000 10439113792 10891411456 1166310656 12.462 99 31 62 0 1535450256 360699168 8440432
06:08:36 18870 8192000000 10394234528 10905866240 1156186240 12.368 100 25 50 0 1518610352 355741376 6682736
06:09:57 16092 8192000000 10215357248 10707984384 1149402800 12.304 99 29 58 0 1355990208 199796432 6790976
06:11:14 48789 8192000000 10119780864 10550611968 1134259296 12.161 100 17 33 1 1230682528 91999120 4424112
06:13:35 102212 8192000000 10115277152 10581585920 1114935520 11.979 97 14 29 0 1241150880 123133424 3081936
06:16:26 75506 8192000000 10082600640 10533040128 1106433184 11.899 97 34 61 3 1153821840 41552096 5836560
06:18:43 170497 8192000000 10086208304 10521358336 1107666320 11.910 97 25 45 1 1157337296 44851296 4819680
06:22:34 30961 8192000000 10131419552 10587729920 1130583584 12.127 99 36 73 0 1244452992 105545744 8323664
06:24:14 29806 8192000000 10167555872 10626097152 1115895312 11.988 99 48 94 2 1253318864 126926688 10496864
06:25:53 38134 8192000000 10073565104 10577563648 1127816240 12.101 100 13 25 0 1213393696 83123936 2453520
06:27:36 32250 8192000000 10083525504 10549981184 1127932336 12.102 99 11 21 1 1188672944 58792896 1947712
06:29:08 84523 8192000000 10079019376 10567815168 1146166784 12.274 100 15 31 4 1205213456 56145616 2901056
06:31:53 94938 8192000000 10080679296 10529570816 1138847920 12.205 99 16 32 0 1183021008 40774336 3398752
06:34:31 26984 8192000000 10160217184 10590212096 1134361952 12.162 98 66 126 1 1217907360 70555008 12990400
06:36:00 44652 8192000000 10171872464 10653532160 1142981648 12.244 99 31 62 2 1286175648 136409872 6784128
06:37:50 43125 8192000000 10178010256 10633388032 1136807600 12.185 98 29 58 1 1269698272 126482592 6408080
06:39:37 48926 8192000000 10115962464 10554028032 1130727616 12.128 99 34 65 3 1205494832 68151616 6615600
06:41:27 111385 8192000000 10071563280 10524774400 1097482816 11.814 96 10 20 0 1143144192 43822768 1838608
06:45:37 101605 8192000000 10077368384 10572562432 1112127056 11.953 98 20 40 1 1179709120 63166832 4415232
06:48:22 40439 8192000000 10094068800 10550034432 1131418736 12.135 99 36 72 1 1186389408 46949248 8021424
06:50:23 68036 8192000000 10069686032 10554408960 1122652432 12.052 98 14 28 0 1183936000 57577904 3705664
06:52:33 54219 8192000000 10080537280 10529882112 1123912544 12.064 99 20 40 0 1181747952 53638096 4197312
06:54:28 74403 8192000000 10083204784 10540986368 1123773392 12.063 99 19 38 0 1161676464 33957456 3945616
06:56:47 61847 8192000000 10053782384 10553417728 1112213376 11.953 99 9 18 0 1193327424 78921840 2192208
06:58:49 68445 8192000000 10066482912 10509885440 1088061184 11.724 96 14 28 0 1112841824 20988000 3792640
07:00:59 21471 8192000000 10111210448 10546733056 1124210464 12.067 99 35 70 0 1190744624 59572112 6962048
07:02:45 34055 8192000000 10071353488 10520420352 1089491552 11.738 97 31 62 1 1168022656 72064144 6466960
07:04:23 14265 8192000000 10059614192 10500956160 1114535904 11.975 99 35 68 3 1165084080 43641712 6906464
07:05:44 130620 8192000000 10081389200 10543001600 1111444976 11.946 98 21 42 0 1230150224 114318976 4386272
07:08:59 46964 8192000000 10086168736 10521882624 1114297184 11.973 98 34 64 2 1148830640 27432304 7101152
07:10:49 42605 8192000000 10054545008 10486128640 1107255008 11.906 98 46 82 4 1150851440 34625632 8970800
07:12:39 50263 8192000000 10063732000 10504814592 1123587824 12.061 100 36 61 1 1171033104 41109568 6335712
07:14:34 36350 8192000000 10113385328 10579767296 1131224688 12.133 100 41 82 1 1211382880 72189968 7968224
07:16:13 73699 8192000000 10069827568 10535223296 1123377360 12.059 100 7 14 0 1173822528 48762464 1682704
07:19:25 72560 8192000000 10074414208 10526396416 1124943200 12.074 99 23 44 1 1165880128 35822720 5114208
07:21:43 28443 8192000000 10093939824 10536759296 1117284656 12.001 99 25 50 1 1189806160 67364896 5156608
07:23:16 44820 8192000000 10108904736 10551197696 1119957184 12.027 99 23 46 2 1189405872 64939024 4509664
07:25:08 36633 8192000000 10137356352 10573094912 1143280912 12.246 100 40 80 1 1226288240 74941536 8065792
07:26:47 53872 8192000000 10089987760 10573803520 1117055376 11.999 98 28 56 0 1224853392 101532544 6265472
07:28:45 42758 8192000000 10110391472 10539565056 1122087904 12.047 99 34 63 1 1195551792 67388032 6075856
07:30:28 31385 8192000000 10107542544 10549981184 1128772336 12.110 99 44 89 1 1192282320 54889648 8620336
07:32:03 104305 8192000000 10064807600 10520182784 1126439936 12.088 99 26 52 0 1168090752 36594592 5056224
07:34:48 25024 8192000000 10029769056 10485301248 1097859456 11.817 98 14 28 1 1129963424 29792752 2311216
07:36:13 85329 8192000000 9897764928 10367143936 998871024 10.868 88 6 12 1 1046606768 46428640 1307104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-06-2022 07:40:17 to 04-06-2022 07:40:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-06-2022 07:40:34 to 04-06-2022 07:40:36.

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          224983
MON$OLDEST_ACTIVE               224984
MON$OLDEST_SNAPSHOT             224984
MON$NEXT_TRANSACTION            224984
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                       2066464
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
--- end of diff output ---

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

04.06.22 07:40:37. 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
2022-06-04 07:40:39 No severe PSQL-related problems occured
04.06.22 07:40:40. End of report.