Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.02.2023 04:38
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 14760688 kB
MemAvailable 30343620 kB
Buffers 20 kB
Cached 16849572 kB
SwapCached 3140 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.3
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 2023-02-24 04:37:26
DTS_END                         2023-02-24 04:37:26.1220
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/59798
TRN_ID                          220571
ATT_ID                          659
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: 9139.87 37599 1096784
customer order: creation 160.89 460 19307
customer order: refuse 14.99 476 1799
order to supplier: creation 44.84 1446 5381
order to supplier: removal 3.35 191242 402
invoice (draft): creation 45.13 3460 5416
invoice (draft): removal 51.76 1074 6211
invoice accept: apply 41.29 26463 4955
invoice accept: cancel 46.87 1290 5624
customer reserve: creation 3895.72 245 467486
customer reserve: removal 4547.02 110 545642
realization accept: apply 79.96 300 9595
realization accept: cancel 81.04 192 9725
payment from customer: creation 58.30 175 6996
payment from customer: removal 5.37 144 644
payment to supplier: creation 48.02 192 5762
payment to supplier: removal 3.17 109 380
service: total inventory turnovers 3.29 5436 395
service: total monetary turnovers 8.07 379 968
service: aggregate perf. data 0.76 19828 91
service: refresh index statistics 0.04 498955 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-02-2023 04:38:39 to 24-02-2023 04:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5443
TEST_TIME 2 3803
TEST_TIME 3 5137
TEST_TIME 4 5706
TEST_TIME 5 6275
TEST_TIME 6 6027
TEST_TIME 7 5967
TEST_TIME 8 6492
TEST_TIME 9 6685
TEST_TIME 10 6746
TEST_TIME 11 6906
TEST_TIME 12 6785
TEST_TIME 13 6887
TEST_TIME 14 7269
TEST_TIME 15 7255
TEST_TIME 16 7171
TEST_TIME 17 7096
TEST_TIME 18 7339
TEST_TIME 19 7545
TEST_TIME 20 7542
TEST_TIME 21 7768
TEST_TIME 22 7692
TEST_TIME 23 7775
TEST_TIME 24 7876
TEST_TIME 25 7918
TEST_TIME 26 7971
TEST_TIME 27 7932
TEST_TIME 28 8370
TEST_TIME 29 8462
TEST_TIME 30 8651
TEST_TIME 31 9014
TEST_TIME 32 9342
TEST_TIME 33 9352
TEST_TIME 34 9836
TEST_TIME 35 10186
TEST_TIME 36 10690
TEST_TIME 37 10862
TEST_TIME 38 10858
TEST_TIME 39 10616
TEST_TIME 40 10481
TEST_TIME 41 10317
TEST_TIME 42 10237
TEST_TIME 43 10163
TEST_TIME 44 10010
TEST_TIME 45 9998
TEST_TIME 46 9948
TEST_TIME 47 9866
TEST_TIME 48 9889
TEST_TIME 49 10001
TEST_TIME 50 9916
TEST_TIME 51 9839
TEST_TIME 52 9807
TEST_TIME 53 9764
TEST_TIME 54 9819
TEST_TIME 55 9805
TEST_TIME 56 9725
TEST_TIME 57 9837
TEST_TIME 58 9918
TEST_TIME 59 9847
TEST_TIME 60 9854
TEST_TIME 61 9788
TEST_TIME 62 9763
TEST_TIME 63 9795
TEST_TIME 64 9762
TEST_TIME 65 9852
TEST_TIME 66 9825
TEST_TIME 67 9759
TEST_TIME 68 9775
TEST_TIME 69 9666
TEST_TIME 70 9653
TEST_TIME 71 9648
TEST_TIME 72 9544
TEST_TIME 73 9484
TEST_TIME 74 9491
TEST_TIME 75 9473
TEST_TIME 76 9435
TEST_TIME 77 9359
TEST_TIME 78 9309
TEST_TIME 79 9288
TEST_TIME 80 9208
TEST_TIME 81 9227
TEST_TIME 82 9172
TEST_TIME 83 9154
TEST_TIME 84 9196
TEST_TIME 85 9165
TEST_TIME 86 9236
TEST_TIME 87 9206
TEST_TIME 88 9171
TEST_TIME 89 9156
TEST_TIME 90 9166
TEST_TIME 91 9201
TEST_TIME 92 9176
TEST_TIME 93 9154
TEST_TIME 94 9188
TEST_TIME 95 9269
TEST_TIME 96 9349
TEST_TIME 97 9459
TEST_TIME 98 9457
TEST_TIME 99 9461
TEST_TIME 100 9460
TEST_TIME 101 9492
TEST_TIME 102 9498
TEST_TIME 103 9499
TEST_TIME 104 9510
TEST_TIME 105 9437
TEST_TIME 106 9427
TEST_TIME 107 9361
TEST_TIME 108 9368
TEST_TIME 109 9339
TEST_TIME 110 9320
TEST_TIME 111 9277
TEST_TIME 112 9222
TEST_TIME 113 9228
TEST_TIME 114 9214
TEST_TIME 115 9248
TEST_TIME 116 9238
TEST_TIME 117 9221
TEST_TIME 118 9231
TEST_TIME 119 9223
TEST_TIME 120 9164

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-02-2023 04:38:43 to 24-02-2023 04:38:47.

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 1524867 1524867 0 0.00 0 25627 13 0 0
doc_list_biud 1524867 1524867 0 0.00 0 37910 46 0 0
sp_add_invoice_to_stock 5058 4955 103 2.03 36 204639 26463 0 103
sp_cancel_adding_invoice 5624 5624 0 0.00 7 79512 1290 0 0
sp_cancel_client_order 1799 1799 0 0.00 6 15881 476 0 0
sp_cancel_customer_reserve 545642 545642 0 0.00 1 29480 110 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 4 5129 144 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 4 1587 109 0 0
sp_cancel_supplier_invoice 6219 6211 8 0.12 3 37926 1075 0 8
sp_cancel_supplier_order 402 402 0 0.00 12 1589445 191243 0 0
sp_cancel_write_off 9725 9725 0 0.00 2 10283 192 0 0
sp_client_order 19307 19307 0 0.00 6 24055 460 0 0
sp_customer_reserve 467486 467486 0 0.00 1 95537 245 0 0
sp_fill_shopping_cart 30085 30085 0 0.00 1 19219 90 0 0
sp_fill_shopping_cart_clo_res 467450 467450 0 0.00 0 13647 17 0 0
sp_get_clo_for_invoice 4919 4919 0 0.00 0 20626 256 0 0
sp_kill_cost_storno 16373 16373 0 0.00 0 25612 80 0 0
sp_kill_qstorno_ret_qs2qd 1107622 1107622 0 0.00 0 37908 48 0 0
sp_kill_qty_storno 583917 583917 0 0.00 0 37909 110 0 0
sp_lock_selected_doc 20124 20124 0 0.00 0 8674 11 0 0
sp_make_cost_storno 27272 27272 0 0.00 0 17060 81 0 0
sp_multiply_rows_for_pdistr 27272 27272 0 0.00 0 8317 37 0 0
sp_multiply_rows_for_qdistr 19307 19307 0 0.00 0 10153 178 0 0
sp_pay_from_customer 6996 6996 0 0.00 5 16344 176 0 0
sp_pay_to_supplier 5762 5762 0 0.00 6 16111 192 0 0
sp_payment_common 12758 12758 0 0.00 2 9720 43 0 0
sp_qd_handle_on_cancel_clo 1799 1799 0 0.00 0 15862 393 0 0
sp_qd_handle_on_invoice_upd_sts 10543 10543 0 0.00 0 28631 591 0 0
sp_qd_handle_on_reserve_upd_sts 9595 9595 0 0.00 0 23441 77 0 0
sp_reserve_write_off 9595 9595 0 0.00 8 23489 300 0 0
sp_supplier_invoice 5416 5416 0 0.00 8 109869 3461 0 0
sp_supplier_order 5381 5381 0 0.00 9 46055 1447 0 0
srv_aggregate_perf_data 91 91 0 0.00 3215 202839 19828 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 754 134133 31040 0 0
srv_find_qd_qs_mism 1047528 1047528 0 0.00 0 17251 10 0 0
srv_make_invnt_saldo 395 395 0 0.00 99 77520 5436 0 0
srv_make_money_saldo 968 968 0 0.00 2 12250 379 0 0
srv_recalc_idx_stat 5 5 0 0.00 428525 566062 498955 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 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 4957 4957 0 0.00 1 14428 1073 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 2 8554 552 0 0
t$perf-norm:sp_cancel_client_order 1799 1799 0 0.00 0 2322 28 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 2529 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 2215 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 2890 32 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 1779 42 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 132600 7876 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 398 17 0 0
t$perf-norm:sp_client_order 19307 19307 0 0.00 0 6939 40 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 4750 18 0 0
t$perf-norm:sp_pay_from_customer 6996 6996 0 0.00 0 6905 21 0 0
t$perf-norm:sp_pay_to_supplier 5762 5762 0 0.00 0 7955 22 0 0
t$perf-norm:sp_reserve_write_off 9595 9595 0 0.00 0 6720 24 0 0
t$perf-norm:sp_supplier_invoice 5416 5416 0 0.00 0 6457 36 0 0
t$perf-norm:sp_supplier_order 5381 5381 0 0.00 0 5274 38 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 236 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 209 13 0 0
t$perf-norm:srv_make_invnt_saldo 395 395 0 0.00 0 694 19 0 0
t$perf-norm:srv_make_money_saldo 968 968 0 0.00 0 556 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 377 131 0 0
v_add_invoice_to_stock 10038 9832 206 2.05 0 4487 27 0 206
v_all_customers 36180 36180 0 0.00 0 90 4 0 0
v_all_suppliers 29988 29988 0 0.00 0 73 5 0 0
v_all_wares 38602 38602 0 0.00 0 67 4 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 1826 22 0 0
v_cancel_client_order 3598 3598 0 0.00 0 2378 11 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 5042 18 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 2370 13 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 1508 13 0 0
v_cancel_supplier_invoice 780 764 16 2.05 0 2172 28 0 16
v_cancel_supplier_order 804 804 0 0.00 0 475 12 0 0
v_cancel_write_off 1904 1904 0 0.00 0 1993 28 0 0
v_max_id_clo_ord 5377 5377 0 0.00 0 487 5 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 3940 16 0 0
v_max_id_ord_sup 5407 5407 0 0.00 0 4172 7 0 0
v_max_non_paid_invoice 1552 1552 0 0.00 0 110 8 0 0
v_max_non_paid_realizn 3457 3457 0 0.00 0 240 7 0 0
v_min_id_clo_ord 5377 5377 0 0.00 0 2823 12 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 1922 12 0 0
v_min_id_ord_sup 5407 5407 0 0.00 0 3172 8 0 0
v_min_non_paid_invoice 5762 5762 0 0.00 0 4532 13 0 0
v_min_non_paid_realizn 6996 6996 0 0.00 0 2254 13 0 0
v_our_firm 9500 9500 0 0.00 0 75 5 0 0
v_reserve_write_off 19186 19186 0 0.00 0 5326 11 0 0
x_lock_depdocs_on_canc_invoice 11453 11453 0 0.00 0 18516 134 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 3466 367 0 0
x_make_qstorno_1000_1200 5381 5381 0 0.00 3 46038 1246 0 0
x_make_qstorno_1200_2000 5416 5416 0 0.00 4 109710 3233 0 0
x_make_qstorno_2100_3300 467486 467486 0 0.00 0 95532 214 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-02-2023 04:38:48 to 24-02-2023 04:38:52.

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
02:07:30 3 8192000000 8798504736 8809730048 4525216 0.055 2 1 2 0 4624640 63648 35776
02:08:31 28799 8192000000 9719924944 10019700736 799897808 8.895 100 82 163 0 826529952 6932640 19699504
02:10:02 15882 8192000000 9767436928 10078695424 843532752 9.335 100 84 168 0 874978432 11187056 20258624
02:11:23 14611 8192000000 9796620384 10117959680 873052816 9.630 100 88 176 0 911129840 17817200 20259824
02:12:40 7564 8192000000 9863133056 10196815872 908731264 9.985 100 80 158 0 969132640 43135200 17266176
02:13:53 4098 8192000000 9921223248 10261385216 954202512 10.432 100 90 177 0 1028339120 56027552 18109056
02:15:02 1977 8192000000 9954615952 10305441792 985561664 10.738 100 91 176 0 1068013904 64430176 18022064
02:16:06 1631 8192000000 9986445760 10346033152 1016661488 11.040 100 91 169 0 1107076432 73943184 16471760
02:17:10 1049 8192000000 10013863024 10378428416 1032815632 11.196 100 94 179 1 1132557424 81364864 18376928
02:18:13 1139 8192000000 10004816928 10375725056 1051912128 11.379 100 95 186 3 1124445776 53844752 18688896
02:19:16 1070 8192000000 10018409584 10396917760 1062052832 11.476 100 93 174 2 1140976016 62637600 16285584
02:20:18 1037 8192000000 10063710528 10444165120 1066628208 11.520 100 95 183 3 1187566576 102334864 18603504
02:21:21 1031 8192000000 10028180896 10408603648 1069601712 11.548 100 92 169 2 1147294816 60796624 16896480
02:22:22 2229 8192000000 10038525344 10418016256 1077573136 11.624 100 92 174 3 1165717872 69734240 18410496
02:23:26 1249 8192000000 10071401360 10457059328 1083990400 11.685 100 97 183 2 1189164336 87073472 18100464
02:24:28 1079 8192000000 10046521424 10428801024 1085302528 11.698 100 98 186 1 1169337520 64511952 19523040
02:25:30 5214 8192000000 10052358544 10445455360 1085858224 11.703 100 74 142 0 1165467520 66064960 13544336
02:26:38 1964 8192000000 10047668704 10431127552 1095155936 11.792 100 86 159 3 1164287392 54754064 14377392
02:27:40 2218 8192000000 10057334912 10448285696 1092610400 11.767 100 75 144 0 1180535888 73856880 14068608
02:28:43 686 8192000000 10009084448 10388439040 1047759424 11.339 97 92 171 1 1129972672 62994176 19219072
02:29:45 2994 8192000000 10045581008 10439753728 1090625552 11.749 100 70 137 1 1163921440 59687792 13608096
02:30:49 3561 8192000000 10020541712 10405249024 1072677968 11.578 100 71 130 0 1142149504 56184512 13287024
02:31:54 1289 8192000000 10020385872 10398318592 1064960128 11.504 100 97 186 4 1144474160 61511664 18002368
02:32:56 939 8192000000 10028556448 10402938880 1054990208 11.409 100 96 188 2 1152120880 78553152 18577520
02:33:59 1067 8192000000 9984765920 10352963584 1025924080 11.129 100 94 173 2 1106328544 61863792 18540672
02:35:02 1106 8192000000 9982205520 10352168960 1024493248 11.115 100 94 188 4 1105636976 62419760 18723968
02:36:04 1063 8192000000 9984424000 10356936704 1025574512 11.126 100 94 172 1 1112231024 68315392 18341120
02:37:06 1404 8192000000 10008727776 10391703552 1045128784 11.314 100 74 145 0 1133375136 73393952 14852400
02:38:18 12327 8192000000 9982639328 10374897664 1050998048 11.370 100 59 110 2 1100525728 38760944 10766736
02:39:35 15675 8192000000 10048583968 10437844992 1058372784 11.441 99 58 111 1 1146382320 75629072 12380464
02:41:03 3584 8192000000 10068389936 10460270592 1079110416 11.639 100 67 131 0 1193162768 100007936 14044416
02:42:12 3237 8192000000 10099801072 10490916864 1096253248 11.802 100 84 159 2 1214094048 102978720 14862080
02:43:18 1683 8192000000 10112766640 10509373440 1098243200 11.821 100 85 158 2 1230774240 113357568 19173472
02:44:24 5595 8192000000 10181706544 10569154560 1111268560 11.944 100 81 142 2 1290616928 164686448 14661920
02:45:33 1216 8192000000 10181743328 10590425088 1119875008 12.026 100 92 177 4 1294504384 153960400 20668976
02:46:38 1096 8192000000 10195340608 10602774528 1137669536 12.194 100 90 170 0 1311578000 155619344 18289120
02:47:44 1608 8192000000 10210172080 10609094656 1152040016 12.329 100 84 161 1 1326265216 156651728 17573472
02:48:52 4341 8192000000 10239769456 10646073344 1162032112 12.422 100 68 133 0 1353625104 176112016 15480976
02:50:01 2202 8192000000 10271231248 10686693376 1172110768 12.517 100 86 170 2 1383855728 191880096 19864864
02:51:06 1347 8192000000 10297778720 10699763712 1182430352 12.613 100 94 180 1 1406396768 203781616 20184800
02:52:10 1809 8192000000 10319227264 10718437376 1162560624 12.427 100 82 160 0 1426414448 246227664 17626160
02:53:15 1408 8192000000 10349374976 10756517888 1173209216 12.527 100 96 188 2 1458428416 264622128 20597072
02:54:22 2231 8192000000 10382857072 10816913408 1191230544 12.695 100 94 176 1 1491445216 280913184 19301488
02:55:28 1375 8192000000 10388480992 10807328768 1199614400 12.773 100 89 169 0 1494820144 275534752 19670992
02:56:34 2048 8192000000 10430739696 10857263104 1191466448 12.697 99 93 185 4 1534353008 321647296 21239264
02:57:40 956 8192000000 10461521792 10881384448 1208237872 12.853 100 92 178 0 1568382976 338902528 21242576
02:58:47 767 8192000000 10485105408 10901721088 1207694400 12.848 100 98 193 0 1591065824 360035312 23336112
02:59:51 1827 8192000000 10509151696 10924773376 1178473376 12.576 100 94 182 0 1613703056 413818784 21410896
03:00:56 747 8192000000 10544131600 10967724032 1194156592 12.722 100 93 180 1 1647523952 432048064 21319296
03:02:00 955 8192000000 10556591984 10985877504 1200546800 12.781 100 96 186 0 1673462720 450290784 22625136
03:03:05 1263 8192000000 10572454512 10981675008 1185407968 12.641 99 92 179 3 1678062000 471083040 21570992
03:04:11 907 8192000000 10598998976 11040833536 1192769008 12.709 100 92 179 1 1701161056 486395216 21996832
03:05:15 1133 8192000000 10571258256 11061092352 1191631424 12.699 100 97 193 1 1668787920 454490336 22666160
03:06:20 7040 8192000000 10561924960 11013578752 1169506384 12.492 99 62 122 1 1656946592 471833856 15606352
03:07:52 10680 8192000000 10537250608 11005235200 1171004976 12.506 100 38 76 0 1636594224 454972304 10616944
03:09:34 9246 8192000000 10422725840 10910056448 1150077904 12.310 99 36 70 1 1549082928 389641744 9363280
03:10:48 24001 8192000000 10407253648 10903097344 1141942912 12.234 100 34 68 0 1528281632 377242384 9096336
03:12:13 18594 8192000000 10259653584 10753032192 1155063088 12.357 100 30 58 0 1393741088 231653056 7024944
03:13:55 58968 8192000000 10087621216 10617999360 1101482848 11.852 98 5 10 0 1207828000 105052160 1292992
03:15:55 48502 8192000000 10074508160 10504114176 1118800576 12.016 99 39 76 0 1162710336 34861888 9047872
03:17:47 113197 8192000000 10081355088 10510413824 1107381168 11.908 98 44 79 0 1166654688 50989488 8284032
03:20:42 73666 8192000000 10106703472 10539360256 1116665280 11.995 99 50 94 1 1192575616 65988112 9922224
03:22:59 18314 8192000000 10135740928 10575495168 1126998896 12.093 99 39 76 0 1249003888 113724880 8280112
03:24:19 17195 8192000000 10169948176 10607423488 1147253376 12.284 100 45 87 1 1267607760 110463824 9890560
03:25:40 15613 8192000000 10165697168 10612760576 1123981424 12.065 98 46 87 2 1236535216 103076496 9477296
03:27:06 109964 8192000000 10136601856 10608181248 1119457520 12.022 98 22 44 0 1228022672 103671264 4893888
03:29:57 42391 8192000000 10137746768 10605948928 1121144528 12.038 99 21 43 0 1234954336 109065504 4744304
03:31:44 39139 8192000000 10122325664 10569035776 1126953328 12.093 99 15 31 0 1185321424 54653120 3714976
03:33:35 20157 8192000000 10150901664 10584064000 1131146176 12.132 100 58 110 3 1237537664 92673328 13718160
03:34:58 64419 8192000000 10054212784 10501795840 1077522176 11.624 95 12 24 1 1105835552 26038880 2274496
03:37:11 14024 8192000000 10091763264 10529890304 1105110240 11.886 98 41 80 0 1164525840 51095664 8319936
03:38:25 18934 8192000000 10077339616 10494390272 1105759664 11.892 98 64 124 1 1155791072 36830880 13200528
03:39:47 41200 8192000000 10126443424 10591047680 1106217696 11.897 98 25 49 0 1205231520 94073808 4940016
03:41:28 97248 8192000000 10089246480 10597195776 1109890432 11.931 98 12 22 0 1147868144 35609280 2368432
03:44:06 93004 8192000000 10114544496 10629373952 1120904800 12.036 98 19 38 0 1218296144 93655856 3735488
03:46:39 133885 8192000000 10056496288 10559815680 1093482720 11.776 96 24 48 0 1201572208 102337312 5752176
03:50:02 14020 8192000000 10076681024 10517856256 1125406336 12.078 99 33 60 0 1170133520 38902864 5824320
03:51:17 78445 8192000000 10050820624 10523156480 1102648832 11.863 97 20 40 0 1164545984 57765312 4131840
03:53:39 51461 8192000000 10059124704 10508029952 1118992608 12.017 99 12 24 0 1144423296 23045376 2385312
03:55:32 57480 8192000000 10087491968 10564169728 1135333744 12.172 100 12 24 0 1195657424 57979856 2343824
03:58:16 45243 8192000000 10068955248 10548908032 1122421968 12.050 99 18 36 0 1162054352 35898368 3734016
04:00:01 94383 8192000000 10062048160 10546450432 1056988896 11.428 93 11 22 0 1117132192 57922464 2220832
04:02:37 53778 8192000000 10092657104 10548260864 1087388496 11.718 96 25 50 2 1172524944 79444336 5692112
04:04:42 7396 8192000000 10080334848 10516271104 1121380256 12.040 100 49 96 0 1175101728 43447120 10274352
04:05:50 19172 8192000000 10136829200 10572320768 1132738944 12.147 100 52 102 1 1236468528 92832032 10897552
04:07:16 20325 8192000000 10173241120 10607890432 1129736256 12.119 99 53 104 0 1268756752 127727280 11293216
04:08:37 28074 8192000000 10188622000 10653544448 1143477520 12.248 100 34 68 0 1287476384 136476752 7522112
04:10:07 11279 8192000000 10245177888 10682179584 1140810992 12.223 99 56 110 1 1335382512 182998336 11573184
04:11:21 39424 8192000000 10257933200 10729062400 1138339488 12.200 99 30 60 0 1353680288 208183536 7157264
04:13:01 13711 8192000000 10197852000 10654023680 1128863296 12.111 99 42 79 0 1287137280 149586000 8687984
04:14:15 34290 8192000000 10107149888 10616246272 1128367440 12.106 100 12 25 0 1204812288 74267728 2177120
04:15:54 68368 8192000000 10113142160 10572967936 1081574576 11.662 95 17 32 0 1153575488 67757216 4243696
04:18:09 54354 8192000000 10072455264 10561765376 1080976752 11.657 96 15 29 0 1153142016 69456144 2709120
04:20:04 134132 8192000000 10080316704 10539798528 1074447712 11.595 95 12 24 0 1147647120 70728640 2470768
04:24:52 15842 8192000000 10093738976 10554122240 1118666032 12.014 99 44 83 3 1186603184 59498912 8438240
04:26:08 63500 8192000000 10102952608 10572632064 1131941728 12.140 99 18 36 0 1196541904 61115040 3485136
04:28:16 22056 8192000000 10065848688 10504556544 1119089488 12.018 99 14 25 0 1167356032 45896688 2369856
04:29:38 51888 8192000000 10107917728 10559377408 1128245440 12.105 100 16 32 0 1201632432 70019680 3367312
04:31:30 71628 8192000000 10085718864 10540072960 1078826240 11.636 95 18 36 0 1132617360 50238576 3552544
04:33:49 34270 8192000000 10074473184 10528759808 1123036560 12.056 100 20 41 0 1173363280 46092624 4234096
04:35:35 30017 8192000000 10100032096 10556006400 1118269984 12.011 99 31 61 1 1164625888 40060912 6294992
04:37:16 21411 8192000000 8981635168 9230376960 132385040 1.590 12 7 14 0 182354960 48224624 1745296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 24-02-2023 04:38:54 to 24-02-2023 04:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 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 24-02-2023 04:39:12 to 24-02-2023 04:39:14.

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          218434
MON$OLDEST_ACTIVE               221512
MON$OLDEST_SNAPSHOT             221512
MON$NEXT_TRANSACTION            221512
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                       2080160
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

24.02.23 04:39:15. 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
2023-02-24 04:39:17 No severe PSQL-related problems occured
24.02.23 04:39:19. End of report.