Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.03.2023 07: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 14793432 kB
MemAvailable 30429548 kB
Buffers 0 kB
Cached 16912992 kB
SwapCached 3164 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-03-17 07:37:25
DTS_END                         2023-03-17 07:37:25.9440
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/45700
TRN_ID                          220988
ATT_ID                          638
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: 9042.63 23131 1085115
customer order: creation 178.89 503 21467
customer order: refuse 14.94 477 1793
order to supplier: creation 47.02 1480 5642
order to supplier: removal 3.44 173597 413
invoice (draft): creation 47.99 3275 5759
invoice (draft): removal 48.71 1071 5845
invoice accept: apply 43.64 26882 5237
invoice accept: cancel 44.18 1375 5302
customer reserve: creation 4028.51 252 483421
customer reserve: removal 4281.44 107 513773
realization accept: apply 85.86 315 10303
realization accept: cancel 81.63 176 9796
payment from customer: creation 61.58 201 7390
payment from customer: removal 6.05 164 726
payment to supplier: creation 51.90 223 6228
payment to supplier: removal 3.58 139 429
service: total inventory turnovers 4.13 4483 496
service: total monetary turnovers 8.10 395 972
service: aggregate perf. data 0.98 15132 117
service: refresh index statistics 0.05 232375 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-03-2023 07:38:46 to 17-03-2023 07:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8140
TEST_TIME 2 8072
TEST_TIME 3 7588
TEST_TIME 4 7070
TEST_TIME 5 7133
TEST_TIME 6 7194
TEST_TIME 7 7082
TEST_TIME 8 6978
TEST_TIME 9 7370
TEST_TIME 10 7150
TEST_TIME 11 7154
TEST_TIME 12 7247
TEST_TIME 13 7172
TEST_TIME 14 7246
TEST_TIME 15 7315
TEST_TIME 16 7308
TEST_TIME 17 7326
TEST_TIME 18 7349
TEST_TIME 19 7557
TEST_TIME 20 7739
TEST_TIME 21 7639
TEST_TIME 22 7703
TEST_TIME 23 7782
TEST_TIME 24 7960
TEST_TIME 25 7972
TEST_TIME 26 8081
TEST_TIME 27 8365
TEST_TIME 28 8542
TEST_TIME 29 8482
TEST_TIME 30 8473
TEST_TIME 31 8481
TEST_TIME 32 8763
TEST_TIME 33 9223
TEST_TIME 34 9503
TEST_TIME 35 9464
TEST_TIME 36 9876
TEST_TIME 37 10196
TEST_TIME 38 10390
TEST_TIME 39 10389
TEST_TIME 40 10815
TEST_TIME 41 10679
TEST_TIME 42 10553
TEST_TIME 43 10527
TEST_TIME 44 10484
TEST_TIME 45 10549
TEST_TIME 46 10598
TEST_TIME 47 10448
TEST_TIME 48 10356
TEST_TIME 49 10379
TEST_TIME 50 10298
TEST_TIME 51 10113
TEST_TIME 52 10018
TEST_TIME 53 10134
TEST_TIME 54 10010
TEST_TIME 55 9885
TEST_TIME 56 9822
TEST_TIME 57 9815
TEST_TIME 58 9744
TEST_TIME 59 9725
TEST_TIME 60 9807
TEST_TIME 61 9778
TEST_TIME 62 9761
TEST_TIME 63 9726
TEST_TIME 64 9629
TEST_TIME 65 9633
TEST_TIME 66 9594
TEST_TIME 67 9568
TEST_TIME 68 9532
TEST_TIME 69 9576
TEST_TIME 70 9525
TEST_TIME 71 9547
TEST_TIME 72 9464
TEST_TIME 73 9664
TEST_TIME 74 9625
TEST_TIME 75 9606
TEST_TIME 76 9641
TEST_TIME 77 9574
TEST_TIME 78 9676
TEST_TIME 79 9697
TEST_TIME 80 9652
TEST_TIME 81 9576
TEST_TIME 82 9543
TEST_TIME 83 9499
TEST_TIME 84 9422
TEST_TIME 85 9445
TEST_TIME 86 9421
TEST_TIME 87 9387
TEST_TIME 88 9355
TEST_TIME 89 9301
TEST_TIME 90 9235
TEST_TIME 91 9207
TEST_TIME 92 9281
TEST_TIME 93 9324
TEST_TIME 94 9297
TEST_TIME 95 9277
TEST_TIME 96 9271
TEST_TIME 97 9263
TEST_TIME 98 9306
TEST_TIME 99 9320
TEST_TIME 100 9315
TEST_TIME 101 9411
TEST_TIME 102 9395
TEST_TIME 103 9431
TEST_TIME 104 9483
TEST_TIME 105 9445
TEST_TIME 106 9378
TEST_TIME 107 9382
TEST_TIME 108 9379
TEST_TIME 109 9345
TEST_TIME 110 9325
TEST_TIME 111 9305
TEST_TIME 112 9271
TEST_TIME 113 9238
TEST_TIME 114 9180
TEST_TIME 115 9160
TEST_TIME 116 9147
TEST_TIME 117 9158
TEST_TIME 118 9156
TEST_TIME 119 9126
TEST_TIME 120 9084

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-03-2023 07:38:50 to 17-03-2023 07:38:54.

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 1530292 1530292 0 0.00 0 36380 15 0 0
doc_list_biud 1530286 1530286 0 0.00 0 33919 43 0 0
sp_add_invoice_to_stock 5392 5237 155 2.87 28 153042 26883 0 155
sp_cancel_adding_invoice 5302 5302 0 0.00 5 82831 1376 0 0
sp_cancel_client_order 1793 1793 0 0.00 6 8649 478 0 0
sp_cancel_customer_reserve 513773 513773 0 0.00 1 33952 108 0 0
sp_cancel_pay_from_customer 726 726 0 0.00 3 6111 165 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 4 9628 140 0 0
sp_cancel_supplier_invoice 5858 5845 13 0.22 2 17707 1072 0 13
sp_cancel_supplier_order 413 413 0 0.00 19 1601880 173597 0 0
sp_cancel_write_off 9796 9796 0 0.00 2 17865 177 0 0
sp_client_order 21467 21467 0 0.00 5 32373 503 0 0
sp_customer_reserve 483421 483421 0 0.00 0 38425 252 0 0
sp_fill_shopping_cart 32858 32858 0 0.00 1 51370 89 0 0
sp_fill_shopping_cart_clo_res 483377 483377 0 0.00 0 12320 17 0 0
sp_get_clo_for_invoice 5185 5185 0 0.00 0 41622 260 0 0
sp_kill_cost_storno 16253 16253 0 0.00 0 36373 89 0 0
sp_kill_qstorno_ret_qs2qd 1043600 1043600 0 0.00 0 33913 46 0 0
sp_kill_qty_storno 552410 552410 0 0.00 0 33917 108 0 0
sp_lock_selected_doc 21361 21361 0 0.00 0 14199 10 0 0
sp_make_cost_storno 29106 29106 0 0.00 0 22579 91 0 0
sp_multiply_rows_for_pdistr 29106 29106 0 0.00 0 7720 42 0 0
sp_multiply_rows_for_qdistr 21467 21467 0 0.00 0 11190 164 0 0
sp_pay_from_customer 7390 7390 0 0.00 5 13452 201 0 0
sp_pay_to_supplier 6228 6228 0 0.00 6 14311 224 0 0
sp_payment_common 13617 13617 0 0.00 2 6282 51 0 0
sp_qd_handle_on_cancel_clo 1793 1793 0 0.00 0 7631 395 0 0
sp_qd_handle_on_invoice_upd_sts 10487 10487 0 0.00 0 13303 597 0 0
sp_qd_handle_on_reserve_upd_sts 10303 10303 0 0.00 0 19202 68 0 0
sp_reserve_write_off 10303 10303 0 0.00 8 22659 315 0 0
sp_supplier_invoice 5759 5759 0 0.00 9 98868 3276 0 0
sp_supplier_order 5642 5642 0 0.00 7 37836 1480 0 0
srv_aggregate_perf_data 117 117 0 0.00 2438 176872 15133 0 0
srv_fill_mon_cache_memory 76 76 0 0.00 819 111850 29154 0 0
srv_find_qd_qs_mism 1033978 1033978 0 0.00 0 13410 11 0 0
srv_make_invnt_saldo 496 496 0 0.00 81 78275 4484 0 0
srv_make_money_saldo 972 972 0 0.00 1 10242 395 0 0
srv_recalc_idx_stat 6 6 0 0.00 29968 377967 232375 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5248 5248 0 0.00 1 32869 1173 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 2 5689 525 0 0
t$perf-norm:sp_cancel_client_order 1793 1793 0 0.00 0 2934 31 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 2386 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 726 726 0 0.00 0 951 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 513 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 2358 43 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 105426 7961 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 5602 23 0 0
t$perf-norm:sp_client_order 21467 21467 0 0.00 0 9291 43 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 623 14 0 0
t$perf-norm:sp_pay_from_customer 7390 7390 0 0.00 0 5423 20 0 0
t$perf-norm:sp_pay_to_supplier 6228 6228 0 0.00 0 4520 23 0 0
t$perf-norm:sp_reserve_write_off 10303 10303 0 0.00 0 12628 28 0 0
t$perf-norm:sp_supplier_invoice 5759 5759 0 0.00 0 12555 41 0 0
t$perf-norm:sp_supplier_order 5642 5642 0 0.00 0 6896 36 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 341 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 76 76 0 0.00 0 213 7 0 0
t$perf-norm:srv_make_invnt_saldo 496 496 0 0.00 0 448 15 0 0
t$perf-norm:srv_make_money_saldo 972 972 0 0.00 0 431 6 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 6 192 78 0 0
v_add_invoice_to_stock 10670 10360 310 2.90 0 6345 28 0 310
v_all_customers 38668 38668 0 0.00 0 115 4 0 0
v_all_suppliers 31750 31750 0 0.00 0 119 5 0 0
v_all_wares 42930 42930 0 0.00 0 155 5 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 480 16 0 0
v_cancel_client_order 3586 3586 0 0.00 0 1272 11 0 0
v_cancel_customer_prepayment 1452 1452 0 0.00 0 2145 14 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 1 1476 13 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 5878 21 0 0
v_cancel_supplier_invoice 784 758 26 3.31 0 546 24 0 26
v_cancel_supplier_order 826 826 0 0.00 0 764 14 0 0
v_cancel_write_off 2066 2066 0 0.00 0 1840 27 0 0
v_max_id_clo_ord 5641 5641 0 0.00 0 135 5 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 2242 12 0 0
v_max_id_ord_sup 5750 5750 0 0.00 0 1721 6 0 0
v_max_non_paid_invoice 1742 1742 0 0.00 0 143 8 0 0
v_max_non_paid_realizn 3984 3984 0 0.00 0 489 8 0 0
v_min_id_clo_ord 5641 5641 0 0.00 0 2866 10 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 908 10 0 0
v_min_id_ord_sup 5750 5750 0 0.00 0 7749 10 0 0
v_min_non_paid_invoice 6226 6226 0 0.00 0 5599 18 0 0
v_min_non_paid_realizn 7390 7390 0 0.00 0 6256 21 0 0
v_our_firm 11074 11074 0 0.00 0 120 5 0 0
v_reserve_write_off 20603 20603 0 0.00 0 4242 13 0 0
x_lock_depdocs_on_canc_invoice 10768 10768 0 0.00 0 18348 142 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 19995 450 0 0
x_make_qstorno_1000_1200 5642 5642 0 0.00 3 37774 1301 0 0
x_make_qstorno_1200_2000 5759 5759 0 0.00 4 76385 3031 0 0
x_make_qstorno_2100_3300 483421 483421 0 0.00 0 38231 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-03-2023 07:38:55 to 17-03-2023 07:38:59.

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:30 915 8192000000 8929296416 8986443776 17422720 0.212 4 3 6 0 18217408 194192 600496
05:08:32 18579 8192000000 9724036736 10020278272 801876464 8.915 100 90 178 0 830181616 6922704 21382448
05:09:57 16810 8192000000 9770483760 10080755712 846510320 9.365 100 86 170 0 876696096 10672464 19513312
05:11:18 18734 8192000000 9810014304 10131386368 882704608 9.727 100 84 168 0 917576560 16193152 18678800
05:12:49 13039 8192000000 9854607888 10187857920 915410224 10.051 100 81 162 0 963423120 30237472 17775424
05:14:07 4975 8192000000 9910648560 10248851456 951046080 10.401 100 87 168 2 1020742464 52441648 17254736
05:15:16 3371 8192000000 9970642576 10320838656 984912480 10.732 100 84 168 0 1078199392 76394400 16892512
05:16:25 2001 8192000000 9997098160 10352726016 1006807104 10.944 100 93 181 0 1112700128 87513664 18379360
05:17:30 1093 8192000000 10028702576 10390429696 1026808480 11.138 100 93 175 1 1141596704 97194160 17594064
05:18:33 873 8192000000 9989097264 10360610816 1045358528 11.316 100 82 156 0 1110528240 49366992 15802720
05:19:38 1430 8192000000 10046584224 10421444608 1056349584 11.422 100 96 188 2 1164404560 89913072 18141904
05:20:43 1653 8192000000 10027734768 10406199296 1069111296 11.544 100 93 168 1 1145736736 59506032 17119408
05:21:47 1098 8192000000 10056050432 10442432512 1073881280 11.589 100 91 174 1 1178614528 86475456 18257792
05:22:50 1277 8192000000 10078130800 10461331456 1082607696 11.672 100 96 184 1 1199858816 98845264 18405856
05:23:54 1192 8192000000 10053442656 10436136960 1084204352 11.688 100 93 174 0 1176765552 74472976 18088224
05:24:58 1333 8192000000 10046776848 10435571712 1096820144 11.807 100 93 171 0 1170678192 55649344 18208704
05:26:00 1356 8192000000 10069962976 10456879104 1102043376 11.857 100 93 175 1 1191024336 70165728 18815232
05:27:03 1212 8192000000 10081283568 10472611840 1102097728 11.858 100 94 182 0 1204489424 83014240 19377456
05:28:05 820 8192000000 10071295520 10461499392 1100746256 11.845 100 80 150 0 1193622288 76731664 16144368
05:29:07 1384 8192000000 10080169344 10470608896 1111198816 11.944 100 98 184 0 1196753056 67025872 18528368
05:30:10 1238 8192000000 10063804000 10450907136 1099052128 11.829 100 96 188 1 1181771440 63109168 19610144
05:31:14 983 8192000000 10059307056 10446327808 1091918288 11.761 100 96 177 1 1183218000 72558624 18741088
05:32:15 1221 8192000000 10041436624 10423549952 1080679120 11.654 100 98 186 0 1162854896 63120736 19055040
05:33:19 6601 8192000000 10000093136 10393317376 1059205888 11.449 99 57 102 3 1134488608 64532768 10749952
05:34:27 871 8192000000 10035749440 10423144448 1069569472 11.548 100 82 156 1 1155676928 68811984 17295472
05:35:30 883 8192000000 9996814512 10373505024 1045181680 11.314 100 90 172 2 1124930400 60439952 19308768
05:36:32 1150 8192000000 9986802688 10358099968 1033148992 11.199 100 93 176 0 1107365792 55284224 18932576
05:37:38 1658 8192000000 10021696240 10401366016 1059074192 11.448 100 86 163 2 1145282096 68172960 18034944
05:38:51 3908 8192000000 10027136224 10412740608 1076318448 11.612 100 72 134 5 1148278496 59588496 12371552
05:40:00 3352 8192000000 10012245264 10397835264 1067185792 11.525 99 61 115 0 1135118768 56500064 11432912
05:41:08 6605 8192000000 10043860320 10434416640 1077306144 11.622 100 66 129 1 1166947792 76344000 13297648
05:42:23 1068 8192000000 10086318544 10464952320 1080497568 11.652 99 91 177 3 1206618864 106597392 19523904
05:43:28 3567 8192000000 10072089168 10468122624 1092654448 11.768 100 81 150 1 1184482176 75955792 15871936
05:44:34 1006 8192000000 10117493440 10504212480 1105086416 11.886 100 82 158 1 1231311456 110710720 15514320
05:45:37 886 8192000000 10144643328 10541019136 1118003120 12.008 100 98 194 0 1261747552 123384352 20360080
05:46:41 2319 8192000000 10181202848 10582241280 1126569520 12.089 100 93 177 3 1295945264 149473312 19902432
05:47:47 10795 8192000000 10213537904 10613424128 1143105328 12.245 100 67 134 0 1323410144 166253488 14051328
05:49:03 795 8192000000 10239252224 10635902976 1158336304 12.388 100 98 185 0 1357748064 177446688 21965072
05:50:07 1498 8192000000 10266580960 10668875776 1169466752 12.492 100 78 146 1 1380557280 194102160 16988368
05:51:11 2080 8192000000 10302598224 10709245952 1173497904 12.530 100 96 180 1 1419342160 226376880 19467376
05:52:16 1846 8192000000 10328250224 10734137344 1168823568 12.486 99 96 182 1 1440050160 249812304 21414288
05:53:22 1312 8192000000 10344143184 10755969024 1179496992 12.586 100 87 168 1 1454894640 256594896 18802752
05:54:28 1324 8192000000 10370174704 10779422720 1169409072 12.491 100 99 189 3 1474933536 283730752 21793712
05:55:34 1032 8192000000 10413474240 10824523776 1186283584 12.649 100 98 186 1 1521917184 313627696 22005904
05:56:38 1276 8192000000 10420962832 10865356800 1184463168 12.632 100 96 193 0 1532129024 323654096 24011760
05:57:43 1057 8192000000 10453974112 10864164864 1187281872 12.658 100 94 181 2 1559385776 350999216 21104688
05:58:51 837 8192000000 10471191824 10889482240 1196813680 12.747 100 98 185 2 1577832544 360309072 20709792
05:59:55 1176 8192000000 10460681552 10900168704 1187581136 12.661 100 96 186 1 1570783312 358849088 24353088
06:01:00 1036 8192000000 10490211648 10927448064 1178645968 12.578 100 98 190 2 1595542176 392800288 24095920
06:02:05 934 8192000000 10521659712 10948558848 1192848080 12.710 100 95 185 2 1621716016 406717536 22150400
06:03:11 1075 8192000000 10524751152 10954440704 1191844704 12.701 100 94 179 1 1625166016 412376896 20944416
06:04:14 1161 8192000000 10551949920 11000315904 1191467248 12.697 100 95 185 0 1657483296 442551936 23464112
06:05:20 1563 8192000000 10498538016 10945421312 1177391504 12.566 100 73 144 1 1602445744 407483744 17570496
06:06:29 6235 8192000000 10545695712 10967142400 1178237648 12.574 100 64 126 1 1645365024 451517296 15610080
06:07:39 7258 8192000000 10489356256 10976178176 1152069872 12.329 99 59 118 1 1594865680 427785472 15010336
06:08:55 3373 8192000000 10547145344 11015217152 1174307424 12.537 100 98 193 0 1646552992 450268464 21977104
06:10:01 2809 8192000000 10528071456 10995343360 1180988288 12.599 100 65 123 1 1650495520 455056112 14451120
06:11:06 20474 8192000000 10409961744 10861551616 1196485472 12.744 100 32 63 1 1527652944 323324960 7842512
06:12:45 19719 8192000000 10376773088 10893131776 1137420640 12.191 98 31 62 0 1495529024 350154928 7953456
06:14:18 38126 8192000000 10249699824 10747408384 1142422240 12.238 99 29 56 0 1390454624 241280320 6752064
06:15:57 34612 8192000000 10183233536 10644856832 1114736048 11.977 98 33 64 1 1283346720 161884800 6725872
06:17:39 79567 8192000000 10066179664 10512621568 1119619808 12.023 99 17 34 0 1172798912 49585216 3593888
06:20:01 34173 8192000000 10116323440 10561941504 1129297088 12.115 99 38 72 0 1190248320 53575088 7376144
06:21:45 111844 8192000000 10112174432 10638372864 1140230784 12.218 100 16 32 1 1254295584 110443232 3621568
06:24:39 35578 8192000000 10101391728 10521665536 1143847344 12.252 100 52 104 0 1180764640 25176048 11741248
06:26:19 71375 8192000000 10094867520 10548441088 1115733280 11.987 98 21 38 1 1178263760 58287040 4243440
06:28:37 33445 8192000000 10109765536 10552545280 1124919440 12.073 99 49 82 3 1176636432 43092512 8624480
06:30:12 57865 8192000000 10047670528 10500075520 1081714592 11.664 96 27 53 0 1143421312 55778560 5928160
06:32:26 11778 8192000000 10084920368 10518077440 1125780896 12.082 100 42 80 0 1183468944 48657440 9030608
06:33:40 6142 8192000000 10125575168 10551070720 1135171792 12.170 100 71 139 0 1219740720 69892512 14676416
06:34:52 28164 8192000000 10129605104 10570637312 1136696208 12.184 100 21 40 0 1219396368 78235936 4464224
06:36:22 15030 8192000000 10160310384 10605793280 1145689968 12.269 100 73 146 1 1252675920 90936544 16049408
06:37:39 54112 8192000000 10119043280 10594742272 1142180736 12.236 99 13 26 0 1220856112 75829760 2845616
06:39:34 12917 8192000000 10082601728 10555604992 1148368560 12.294 100 29 49 1 1181965744 29442112 4155072
06:40:56 35982 8192000000 10072953888 10510979072 1101701904 11.854 96 13 21 1 1135262784 31001792 2559088
06:42:32 50883 8192000000 10063100400 10567286784 1142748208 12.241 100 8 16 0 1177797856 33278560 1771088
06:44:25 24031 8192000000 10068903072 10517757952 1126401088 12.087 98 32 63 0 1168808432 36479200 5928144
06:45:50 45599 8192000000 10117624800 10578255872 1109459680 11.927 97 22 44 0 1183542976 69621120 4462176
06:48:02 58323 8192000000 10124662112 10572513280 1106837584 11.902 97 28 56 0 1188961840 75461392 6662864
06:50:08 58383 8192000000 10052561616 10515255296 1095516528 11.795 97 14 26 0 1141266992 42760128 2990336
06:52:08 51557 8192000000 10115921696 10565492736 1112303888 11.954 98 33 66 0 1196039072 76982096 6753088
06:54:00 8191 8192000000 10079067776 10500591616 1110755712 11.940 98 54 96 3 1151748848 30784928 10208208
06:55:12 50197 8192000000 10091658608 10592161792 1097866544 11.817 98 28 55 0 1201457264 98164224 5426496
06:57:08 40613 8192000000 10090302256 10529771520 1084679120 11.692 97 35 70 0 1138672352 46861808 7131424
06:58:51 100993 8192000000 10052095824 10514366464 1106678320 11.901 99 21 39 0 1133131408 22712208 3740880
07:01:33 36544 8192000000 10112146576 10565492736 1123039792 12.056 100 38 76 0 1213250640 82455456 7755392
07:03:20 6712 8192000000 10066083856 10507313152 1121110512 12.037 100 49 89 0 1170113936 40525504 8477920
07:04:33 58123 8192000000 10092436656 10557579264 1130882400 12.130 100 22 44 0 1203435472 67524128 5028944
07:07:04 90509 8192000000 10155913712 10614358016 1131387328 12.134 99 15 30 0 1231159424 96138928 3633168
07:09:35 77644 8192000000 10051889696 10515963904 1089944336 11.742 96 21 43 0 1178628720 83756832 4927552
07:11:54 39973 8192000000 10136931360 10573553664 1139132768 12.207 99 41 80 0 1212472752 64457424 8882560
07:13:38 43753 8192000000 10164790832 10611298304 1132056016 12.141 99 25 50 0 1241273536 103787088 5430432
07:15:24 48273 8192000000 10078629152 10540503040 1092894976 11.770 96 21 38 0 1136205056 38929792 4380288
07:17:15 18818 8192000000 10138084864 10568183808 1125026528 12.074 99 50 100 0 1205716208 70698368 9991312
07:18:36 74516 8192000000 10135438240 10596413440 1132732928 12.147 99 29 58 1 1206118288 67266992 6118368
07:20:59 68994 8192000000 10086651744 10555113472 1107956368 11.913 97 23 44 0 1162408800 49747248 4705184
07:23:10 92733 8192000000 10119915440 10622124032 1135058800 12.169 99 20 40 0 1253155040 113525952 4570288
07:25:53 29443 8192000000 10098515168 10587570176 1133348720 12.153 99 32 63 1 1188550352 49138960 6062672
07:27:54 57178 8192000000 10067553888 10562781184 1138040128 12.197 100 14 28 0 1187581136 46660544 2880464
07:29:58 71880 8192000000 10083343088 10547998720 1094493632 11.785 95 10 20 0 1131583168 34858624 2230912
07:32:12 49009 8192000000 10106068224 10589003776 1138579072 12.202 100 20 41 0 1222280992 79100192 4601728
07:34:05 50818 8192000000 10068995472 10522378240 1122612256 12.052 99 17 35 0 1156870096 30202320 4055520
07:35:56 35830 8192000000 10066298704 10505158656 1077236912 11.621 96 18 33 2 1129644128 48776512 3630704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 17-03-2023 07:39:01 to 17-03-2023 07:39:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 155 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-03-2023 07:39:19 to 17-03-2023 07:39:21.

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          215828
MON$OLDEST_ACTIVE               221149
MON$OLDEST_SNAPSHOT             221149
MON$NEXT_TRANSACTION            221149
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                       2090752
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.2909 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2909 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2909 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2909 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 ---
:118: 
:119: mscs-test-161.budzdorov.ru    Fri Mar 17 07:37:28 2023
:120:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:121: 
:122: 
:123: mscs-test-161.budzdorov.ru    Fri Mar 17 07:37:29 2023
:124:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:125: 
:126: 
:127: mscs-test-161.budzdorov.ru    Fri Mar 17 07:37:30 2023
:128:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:129: 
--- end of diff output ---

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

17.03.23 07:39:22. 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-03-17 07:39:24 No severe PSQL-related problems occured
17.03.23 07:39:26. End of report.