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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15240004 kB
MemAvailable 30446752 kB
Buffers 0 kB
Cached 16488944 kB
SwapCached 3052 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-03 13:37:26
DTS_END                         2023-03-03 13:37:26.5450
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59678
TRN_ID                          212515
ATT_ID                          586
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: 8805.59 19651 1056671
customer order: creation 177.32 468 21278
customer order: refuse 14.98 379 1798
order to supplier: creation 46.98 1210 5637
order to supplier: removal 3.05 166937 366
invoice (draft): creation 45.78 3188 5494
invoice (draft): removal 47.35 947 5682
invoice accept: apply 41.84 24951 5021
invoice accept: cancel 43.36 1179 5203
customer reserve: creation 3880.55 239 465666
customer reserve: removal 4210.27 97 505232
realization accept: apply 83.74 235 10049
realization accept: cancel 78.87 154 9464
payment from customer: creation 60.53 161 7263
payment from customer: removal 5.57 92 668
payment to supplier: creation 49.38 183 5925
payment to supplier: removal 3.58 104 429
service: total inventory turnovers 4.24 3196 509
service: total monetary turnovers 7.25 297 870
service: aggregate perf. data 0.94 14709 113
service: refresh index statistics 0.03 174292 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 03-03-2023 13:39:40 to 03-03-2023 13:39:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7358
TEST_TIME 2 6288
TEST_TIME 3 6204
TEST_TIME 4 6433
TEST_TIME 5 6161
TEST_TIME 6 6302
TEST_TIME 7 6080
TEST_TIME 8 6288
TEST_TIME 9 6306
TEST_TIME 10 6340
TEST_TIME 11 6494
TEST_TIME 12 6617
TEST_TIME 13 6812
TEST_TIME 14 6849
TEST_TIME 15 7205
TEST_TIME 16 7221
TEST_TIME 17 7369
TEST_TIME 18 7680
TEST_TIME 19 7848
TEST_TIME 20 8096
TEST_TIME 21 8150
TEST_TIME 22 8220
TEST_TIME 23 8254
TEST_TIME 24 8250
TEST_TIME 25 8646
TEST_TIME 26 9353
TEST_TIME 27 9446
TEST_TIME 28 10156
TEST_TIME 29 10741
TEST_TIME 30 11031
TEST_TIME 31 11215
TEST_TIME 32 11102
TEST_TIME 33 11107
TEST_TIME 34 10826
TEST_TIME 35 10677
TEST_TIME 36 10422
TEST_TIME 37 10271
TEST_TIME 38 10084
TEST_TIME 39 10022
TEST_TIME 40 9897
TEST_TIME 41 10089
TEST_TIME 42 10026
TEST_TIME 43 9946
TEST_TIME 44 9814
TEST_TIME 45 9697
TEST_TIME 46 9578
TEST_TIME 47 9460
TEST_TIME 48 9485
TEST_TIME 49 9363
TEST_TIME 50 9318
TEST_TIME 51 9333
TEST_TIME 52 9342
TEST_TIME 53 9363
TEST_TIME 54 9300
TEST_TIME 55 9326
TEST_TIME 56 9295
TEST_TIME 57 9290
TEST_TIME 58 9429
TEST_TIME 59 9403
TEST_TIME 60 9433
TEST_TIME 61 9496
TEST_TIME 62 9472
TEST_TIME 63 9403
TEST_TIME 64 9332
TEST_TIME 65 9334
TEST_TIME 66 9286
TEST_TIME 67 9259
TEST_TIME 68 9177
TEST_TIME 69 9129
TEST_TIME 70 9204
TEST_TIME 71 9191
TEST_TIME 72 9142
TEST_TIME 73 9117
TEST_TIME 74 9145
TEST_TIME 75 9200
TEST_TIME 76 9200
TEST_TIME 77 9197
TEST_TIME 78 9238
TEST_TIME 79 9252
TEST_TIME 80 9214
TEST_TIME 81 9321
TEST_TIME 82 9256
TEST_TIME 83 9195
TEST_TIME 84 9141
TEST_TIME 85 9132
TEST_TIME 86 9065
TEST_TIME 87 9025
TEST_TIME 88 8996
TEST_TIME 89 8981
TEST_TIME 90 9018
TEST_TIME 91 8999
TEST_TIME 92 8979
TEST_TIME 93 8970
TEST_TIME 94 9001
TEST_TIME 95 9032
TEST_TIME 96 9064
TEST_TIME 97 9101
TEST_TIME 98 9125
TEST_TIME 99 9085
TEST_TIME 100 9150
TEST_TIME 101 9159
TEST_TIME 102 9166
TEST_TIME 103 9127
TEST_TIME 104 9058
TEST_TIME 105 9068
TEST_TIME 106 9053
TEST_TIME 107 9014
TEST_TIME 108 8994
TEST_TIME 109 8956
TEST_TIME 110 8939
TEST_TIME 111 8921
TEST_TIME 112 8905
TEST_TIME 113 8884
TEST_TIME 114 8887
TEST_TIME 115 8868
TEST_TIME 116 8872
TEST_TIME 117 8892
TEST_TIME 118 8875
TEST_TIME 119 8852
TEST_TIME 120 8835

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-03-2023 13:39:44 to 03-03-2023 13:39:48.

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 1485805 1485805 0 0.00 0 29323 13 0 0
doc_list_biud 1485804 1485804 0 0.00 0 46321 38 0 0
sp_add_invoice_to_stock 5179 5021 158 3.05 71 187691 24952 0 158
sp_cancel_adding_invoice 5203 5203 0 0.00 6 97606 1180 0 0
sp_cancel_client_order 1798 1798 0 0.00 6 9078 379 0 0
sp_cancel_customer_reserve 505232 505232 0 0.00 1 46333 98 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 4 2531 93 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 4 1057 105 0 0
sp_cancel_supplier_invoice 5696 5682 14 0.24 2 45145 948 0 14
sp_cancel_supplier_order 366 366 0 0.00 6 1270229 166937 0 0
sp_cancel_write_off 9464 9464 0 0.00 2 29324 155 0 0
sp_client_order 21278 21278 0 0.00 6 30253 469 0 0
sp_customer_reserve 465666 465666 0 0.00 0 44902 239 0 0
sp_fill_shopping_cart 32404 32404 0 0.00 1 24943 74 0 0
sp_fill_shopping_cart_clo_res 465625 465625 0 0.00 0 26468 20 0 0
sp_get_clo_for_invoice 4975 4975 0 0.00 0 28601 290 0 0
sp_kill_cost_storno 15764 15764 0 0.00 0 29321 65 0 0
sp_kill_qstorno_ret_qs2qd 1025976 1025976 0 0.00 0 46308 43 0 0
sp_kill_qty_storno 542769 542769 0 0.00 0 46319 97 0 0
sp_lock_selected_doc 20832 20832 0 0.00 0 8670 6 0 0
sp_make_cost_storno 28212 28212 0 0.00 0 27102 70 0 0
sp_multiply_rows_for_pdistr 28212 28212 0 0.00 0 7469 33 0 0
sp_multiply_rows_for_qdistr 21278 21278 0 0.00 0 12800 201 0 0
sp_pay_from_customer 7263 7263 0 0.00 5 14172 161 0 0
sp_pay_to_supplier 5925 5925 0 0.00 6 12982 184 0 0
sp_payment_common 13188 13188 0 0.00 2 9247 41 0 0
sp_qd_handle_on_cancel_clo 1798 1798 0 0.00 0 9057 312 0 0
sp_qd_handle_on_invoice_upd_sts 10178 10178 0 0.00 0 29892 434 0 0
sp_qd_handle_on_reserve_upd_sts 10049 10049 0 0.00 0 24383 54 0 0
sp_reserve_write_off 10049 10049 0 0.00 8 27135 235 0 0
sp_supplier_invoice 5494 5494 0 0.00 9 116335 3188 0 0
sp_supplier_order 5637 5637 0 0.00 7 45002 1210 0 0
srv_aggregate_perf_data 113 113 0 0.00 2352 115644 14709 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 925 121236 35067 0 0
srv_find_qd_qs_mism 1007150 1007150 0 0.00 0 17439 10 0 0
srv_make_invnt_saldo 509 509 0 0.00 68 80184 3197 0 0
srv_make_money_saldo 870 870 0 0.00 1 8927 297 0 0
srv_recalc_idx_stat 4 4 0 0.00 43326 312993 174292 0 0
t$perf-abend:sp_add_invoice_to_stock 158 158 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5028 5028 0 0.00 1 16978 799 0 0
t$perf-norm:sp_cancel_adding_invoice 456 456 0 0.00 1 19543 426 0 0
t$perf-norm:sp_cancel_client_order 1798 1798 0 0.00 0 3865 29 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 1048 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 214 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 527 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 692 29 0 0
t$perf-norm:sp_cancel_supplier_order 366 366 0 0.00 0 91150 6438 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 1708 17 0 0
t$perf-norm:sp_client_order 21278 21278 0 0.00 0 3204 31 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 1636 12 0 0
t$perf-norm:sp_pay_from_customer 7263 7263 0 0.00 0 9750 19 0 0
t$perf-norm:sp_pay_to_supplier 5925 5925 0 0.00 0 4505 17 0 0
t$perf-norm:sp_reserve_write_off 10049 10049 0 0.00 0 4999 18 0 0
t$perf-norm:sp_supplier_invoice 5494 5494 0 0.00 0 11313 31 0 0
t$perf-norm:sp_supplier_order 5637 5637 0 0.00 0 12568 30 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 498 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 1501 32 0 0
t$perf-norm:srv_make_invnt_saldo 509 509 0 0.00 0 2621 17 0 0
t$perf-norm:srv_make_money_saldo 870 870 0 0.00 0 7244 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 15 206 105 0 0
v_add_invoice_to_stock 10264 9948 316 3.07 0 4219 24 0 316
v_all_customers 39398 39398 0 0.00 0 210 3 0 0
v_all_suppliers 31134 31134 0 0.00 0 80 4 0 0
v_all_wares 42556 42556 0 0.00 0 109 3 0 0
v_cancel_adding_invoice 912 912 0 0.00 0 1592 15 0 0
v_cancel_client_order 3596 3596 0 0.00 0 3697 9 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 2475 13 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 2146 11 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 1016 11 0 0
v_cancel_supplier_invoice 866 838 28 3.23 0 4790 30 0 28
v_cancel_supplier_order 732 732 0 0.00 0 234 8 0 0
v_cancel_write_off 1978 1978 0 0.00 0 2275 23 0 0
v_max_id_clo_ord 5635 5635 0 0.00 0 646 4 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 2978 12 0 0
v_max_id_ord_sup 5490 5490 0 0.00 0 4531 7 0 0
v_max_non_paid_invoice 1482 1482 0 0.00 0 132 7 0 0
v_max_non_paid_realizn 3612 3612 0 0.00 0 668 7 0 0
v_min_id_clo_ord 5635 5635 0 0.00 0 2115 7 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 8922 21 0 0
v_min_id_ord_sup 5490 5490 0 0.00 0 2943 7 0 0
v_min_non_paid_invoice 5925 5925 0 0.00 0 7131 16 0 0
v_min_non_paid_realizn 7263 7263 0 0.00 0 5398 14 0 0
v_our_firm 10460 10460 0 0.00 0 359 4 0 0
v_reserve_write_off 20094 20094 0 0.00 0 4121 10 0 0
x_lock_depdocs_on_canc_invoice 10466 10466 0 0.00 0 20876 110 0 0
x_lock_depdocs_on_canc_sup_ord 366 366 0 0.00 0 7534 325 0 0
x_make_qstorno_1000_1200 5637 5637 0 0.00 2 44964 1072 0 0
x_make_qstorno_1200_2000 5494 5494 0 0.00 4 114463 2967 0 0
x_make_qstorno_2100_3300 465666 465666 0 0.00 0 44901 208 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-03-2023 13:39:49 to 03-03-2023 13:39:53.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:30 2 8192000000 8799252400 8810582016 5260848 0.064 2 1 2 0 5360272 63648 35776
11:08:31 31666 8192000000 9730180576 10030706688 804012432 8.937 100 83 166 0 831804128 7974448 19817248
11:10:08 19538 8192000000 9772391376 10082959360 847455568 9.375 100 87 174 0 880976304 13023072 20497664
11:11:35 12764 8192000000 9805468944 10126254080 874953312 9.649 100 85 170 0 913969872 19129040 19887520
11:12:52 7407 8192000000 9857754944 10195132416 914726640 10.044 100 76 146 0 974280880 43106800 16447440
11:14:05 2146 8192000000 9916320880 10258112512 960044896 10.489 100 91 177 1 1033687280 54835440 18806944
11:15:10 1376 8192000000 9977756528 10329100288 992333616 10.804 100 96 185 1 1092376192 81250320 18792256
11:16:13 998 8192000000 9983927616 10344132608 1015984928 11.033 100 93 174 2 1101253024 67837168 17430928
11:17:17 1220 8192000000 10001603488 10375090176 1041036560 11.275 100 95 179 3 1127454704 66814848 19603296
11:18:19 1060 8192000000 10042818416 10420891648 1057544736 11.433 100 97 184 0 1165262448 88541456 19176256
11:19:21 1248 8192000000 10016965936 10390376448 1063603040 11.491 100 96 186 0 1137817280 55468832 18745408
11:20:24 1275 8192000000 10019192848 10402193408 1067876624 11.532 100 88 149 1 1144058848 60485168 15697056
11:21:26 1074 8192000000 10045175376 10430562304 1072983008 11.581 100 97 186 2 1167175520 75167248 19025264
11:22:28 1127 8192000000 10056296960 10442129408 1079235728 11.640 100 97 192 0 1177346800 79271776 18839296
11:23:30 1203 8192000000 10042391712 10426073088 1080375984 11.651 100 95 180 2 1162140512 63175872 18588656
11:24:32 1044 8192000000 10058467168 10443599872 1087901168 11.723 100 96 181 1 1181142352 73857040 19384144
11:25:33 1067 8192000000 10071152416 10463522816 1091931472 11.761 100 97 181 1 1193949472 82889120 19128880
11:26:36 912 8192000000 10053091680 10440699904 1096223680 11.802 100 92 175 2 1176658848 62955232 17479936
11:27:38 930 8192000000 10065982960 10455584768 1098790272 11.826 100 90 168 2 1189293696 71494320 19009104
11:28:41 1035 8192000000 10062817968 10455097344 1094688384 11.787 100 91 173 1 1182721488 70698976 17334128
11:29:43 1139 8192000000 10027323040 10406019072 1082298624 11.669 100 96 182 1 1148761744 47125744 19337376
11:30:45 1009 8192000000 10036285296 10418405376 1078663312 11.635 100 97 182 1 1160119312 62037056 19418944
11:31:47 1548 8192000000 10019826560 10405613568 1073828128 11.589 100 72 131 3 1142916336 55181856 13906352
11:32:49 1106 8192000000 10008077536 10388234240 1049432304 11.355 100 93 173 4 1131751088 64261664 18057120
11:33:52 1055 8192000000 9983366928 10359783424 1038647376 11.252 100 93 167 2 1105753936 49734592 17371968
11:34:53 1284 8192000000 10001450032 10374225920 1036374896 11.230 100 97 178 2 1128454032 71920800 20158336
11:35:55 1766 8192000000 9975069728 10358140928 1040682544 11.271 100 72 136 1 1097517360 42240080 14594736
11:36:58 3114 8192000000 9963026080 10344681472 1038125568 11.247 100 76 139 3 1085217104 32888608 14202928
11:38:10 16380 8192000000 10013464800 10399551488 1052696560 11.387 100 64 115 1 1139534192 74498016 12339616
11:39:30 2356 8192000000 10030706816 10412761088 1073094928 11.582 100 84 156 2 1148615632 59898448 15622256
11:40:39 20496 8192000000 10081968064 10476236800 1082498096 11.671 100 59 116 0 1197220960 102403824 12319040
11:42:08 3465 8192000000 10072290256 10465570816 1100514416 11.843 100 71 122 1 1189559824 75991968 13053440
11:43:15 1154 8192000000 10130016752 10517045248 1112006464 11.951 100 86 161 2 1243256304 114614912 16634928
11:44:21 18130 8192000000 10179102464 10580721664 1129094000 12.113 100 65 130 1 1282715120 139646592 13974528
11:45:46 1047 8192000000 10207177040 10603003904 1136747248 12.185 100 88 164 0 1321321696 166373280 18201168
11:46:50 1436 8192000000 10248019264 10646597632 1148355616 12.294 100 96 177 2 1361162240 193464832 19341792
11:47:53 1743 8192000000 10296887296 10710441984 1156916416 12.374 100 82 162 0 1408264128 232926016 18421696
11:48:59 1007 8192000000 10328360832 10733961216 1167064560 12.469 100 95 187 1 1442605216 253591648 21949008
11:50:06 2607 8192000000 10337483504 10740232192 1174171056 12.536 100 80 158 1 1449237296 257126640 17939600
11:51:12 1234 8192000000 10358298624 10757419008 1172062784 12.516 100 99 191 1 1468258128 274308368 21886976
11:52:16 1015 8192000000 10385997712 10795065344 1179555504 12.586 100 97 189 1 1492675088 292117280 21002304
11:53:21 1000 8192000000 10425689584 10837262336 1188014592 12.665 100 92 179 2 1530218464 321617824 20586048
11:54:25 955 8192000000 10467311248 10897518592 1195858160 12.738 100 96 182 2 1576419744 358972480 21589104
11:55:27 917 8192000000 10466645200 10894864384 1195870128 12.738 100 97 191 2 1572477664 355296400 21311136
11:56:30 5707 8192000000 10492584960 10916642816 1184848960 12.635 100 72 141 1 1595209376 392527616 17832800
11:57:42 6115 8192000000 10486982640 10933669888 1193357600 12.715 100 60 116 1 1596616656 388277072 14981984
11:58:58 3635 8192000000 10479221200 10930814976 1197602400 12.754 100 54 109 0 1583529744 371895856 14031488
12:00:06 2182 8192000000 10528250960 10953732096 1199394320 12.771 100 70 132 0 1644379552 428763472 16221760
12:01:11 8589 8192000000 10551379520 10982268928 1188180208 12.666 100 52 104 0 1651734832 449929952 13624672
12:02:23 10924 8192000000 10501660464 10994102272 1193972224 12.720 100 44 86 1 1629833632 424668992 11192416
12:03:37 22139 8192000000 10448951008 10911166464 1186380064 12.650 100 30 58 0 1576057312 381807872 7869376
12:05:02 19409 8192000000 10338391776 10829635584 1161661600 12.419 99 25 50 0 1455466448 286853344 6951504
12:06:40 54030 8192000000 10184760528 10677469184 1122487360 12.050 97 20 38 0 1301761472 174357536 4916576
12:08:35 59918 8192000000 10052043936 10506457088 1085393088 11.699 95 19 37 0 1117795408 29294144 3108176
12:10:36 118297 8192000000 10047091712 10496692224 1062736752 11.483 94 16 32 1 1089839056 23970064 3132240
12:13:44 35211 8192000000 10102607632 10535440384 1134791760 12.167 100 36 72 0 1183099728 41032368 7275600
12:15:20 47194 8192000000 10156252592 10607767552 1150904400 12.318 100 53 96 0 1250312880 88037200 11371280
12:17:14 65707 8192000000 10134176144 10591916032 1074679168 11.597 96 13 26 0 1206086544 128237360 3170016
12:19:34 21072 8192000000 10036655472 10500050944 1116336704 11.992 100 23 41 1 1150118960 30185856 3596400
12:20:55 36471 8192000000 10046716416 10486169600 1087589696 11.720 97 23 42 1 1106909200 14544272 4775232
12:22:32 25105 8192000000 10066209552 10506178560 1117376832 12.002 99 17 31 0 1162734512 42165728 3191952
12:24:08 83920 8192000000 10059720128 10565832704 1122849296 12.054 99 17 35 0 1201767520 75115792 3802432
12:26:33 34199 8192000000 10105010416 10549960704 1139082144 12.207 100 37 71 1 1209858368 63544560 7231664
12:28:08 89769 8192000000 10062598464 10524770304 1101875536 11.855 97 10 20 1 1154823520 50282208 2665776
12:30:41 34506 8192000000 10051292736 10497814528 1098116576 11.820 97 17 32 0 1140820480 39467424 3236480
12:32:18 29138 8192000000 10111725264 10566025216 1139960048 12.215 100 36 69 0 1206153536 59109200 7084288
12:34:09 19325 8192000000 10115236608 10548785152 1111267696 11.944 98 57 102 0 1195338400 72977568 11093136
12:35:57 31888 8192000000 10092603744 10528321536 1127783296 12.100 99 29 58 0 1168948976 34783616 6382064
12:37:29 75046 8192000000 10086927776 10580647936 1146173584 12.274 100 16 32 0 1209614368 60272784 3168000
12:39:44 39437 8192000000 10118038832 10568048640 1115335312 11.983 97 38 74 0 1186500544 64179440 6985792
12:41:24 121236 8192000000 10150565472 10626048000 1150391728 12.313 100 30 58 1 1228472352 71836352 6244272
12:44:28 63009 8192000000 10123438000 10582220800 1147848752 12.289 99 11 22 0 1232405104 82151072 2405280
12:46:31 51975 8192000000 10104622080 10620047360 1134760464 12.166 98 11 22 0 1226309104 89097344 2451296
12:48:24 16259 8192000000 10067099120 10500866048 1131275952 12.133 99 38 66 0 1163992576 27127584 5589040
12:49:40 44963 8192000000 10112955904 10557890560 1132028672 12.140 98 28 54 1 1210282944 72103728 6150544
12:51:26 36250 8192000000 10105317264 10573426688 1139875856 12.214 99 38 74 0 1216410496 67582016 8952624
12:53:22 62091 8192000000 10100859888 10540879872 1124199216 12.067 99 27 54 0 1201967600 72098208 5670176
12:55:29 38587 8192000000 10132061440 10591907840 1139291472 12.209 100 39 78 0 1222070336 74980080 7798784
12:57:08 26926 8192000000 10111905328 10536734720 1065959136 11.513 94 47 89 0 1138707488 64180208 8568144
12:58:43 30897 8192000000 10050030256 10476666880 1083374080 11.680 96 33 61 1 1120054496 30335248 6345168
13:00:15 83665 8192000000 10103715136 10552434688 1125350992 12.078 100 41 77 0 1211392096 78128288 7912816
13:02:39 42483 8192000000 10055890768 10529099776 1102842688 11.865 98 9 18 0 1147360176 42531360 1986128
13:04:30 50134 8192000000 10064002624 10551324672 1126411488 12.088 100 10 20 0 1188120480 59257008 2451984
13:07:12 82775 8192000000 10068357744 10508431360 1076828736 11.617 95 24 49 0 1127290192 44748208 5713248
13:09:36 53263 8192000000 10089852800 10544148480 1082758432 11.674 97 46 88 1 1136338368 43672336 9907600
13:11:44 85253 8192000000 10098917248 10547585024 1101580896 11.853 97 19 36 0 1165006432 59246800 4178736
13:14:10 63109 8192000000 10107144928 10593038336 1111736512 11.949 98 32 62 1 1205220096 87349152 6134432
13:16:17 31609 8192000000 10159333440 10581901312 1121432864 12.041 99 43 86 0 1238157264 108002128 8722272
13:17:54 47253 8192000000 10099818304 10559422464 1110794016 11.940 98 24 48 0 1170199216 54649744 4755456
13:19:43 68447 8192000000 10123497264 10595422208 1120537328 12.032 99 39 78 0 1224052288 95544576 7970384
13:23:03 60069 8192000000 10067952816 10595704832 1128773584 12.110 100 5 10 0 1162889840 32759152 1357104
13:25:08 49612 8192000000 10079326736 10545209344 1127251008 12.095 99 15 24 0 1185384304 55878752 2254544
13:27:01 50881 8192000000 10124761168 10568732672 1122548672 12.051 98 19 37 0 1192783168 66274784 3959712
13:28:56 29885 8192000000 10173556272 10617192448 1158022816 12.385 100 28 54 0 1261351472 97318528 6010128
13:30:32 30431 8192000000 10215578048 10658340864 1159017168 12.394 99 41 75 1 1302622864 135337200 8268496
13:32:02 24129 8192000000 10244480368 10682757120 1161999360 12.422 99 45 87 0 1322626160 150305376 10321424
13:33:30 19611 8192000000 10258256400 10688610304 1161326704 12.416 99 43 84 2 1333233088 162326496 9579888
13:34:58 33947 8192000000 10278267600 10718638080 1171766608 12.513 100 31 61 0 1363379296 184247184 7365504
13:36:44 36893 8192000000 10344438944 10818019328 1174824720 12.542 100 40 80 0 1420605504 236376000 9404784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-03-2023 13:39:55 to 03-03-2023 13:40:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 158 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-03-2023 13:40:12 to 03-03-2023 13:40: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          209246
MON$OLDEST_ACTIVE               225099
MON$OLDEST_SNAPSHOT             225099
MON$NEXT_TRANSACTION            225099
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                       2043360
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.2904 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2904 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2904 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2904 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 ---
:148: 
:149: mscs-test-161.budzdorov.ru    Fri Mar  3 13:37:42 2023
:150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:151: 
:152: 
:153: mscs-test-161.budzdorov.ru    Fri Mar  3 13:37:47 2023
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:155: 
:156: 
:157: mscs-test-161.budzdorov.ru    Fri Mar  3 13:38:07 2023
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:159: 
:160: 
:161: mscs-test-161.budzdorov.ru    Fri Mar  3 13:38:24 2023
:162:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:163: 
--- end of diff output ---

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

03.03.23 13:40: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-03-03 13:40:18 No severe PSQL-related problems occured
03.03.23 13:40:19. End of report.