Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.02.2023 10: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 14960084 kB
MemAvailable 30393276 kB
Buffers 0 kB
Cached 16710300 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-22 10:37:25
DTS_END                         2023-02-22 10:37:25.7140
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/32776
TRN_ID                          221326
ATT_ID                          627
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: 8985.13 29188 1078216
customer order: creation 163.22 485 19586
customer order: refuse 14.73 388 1768
order to supplier: creation 44.68 1341 5362
order to supplier: removal 3.18 172615 381
invoice (draft): creation 44.94 3138 5393
invoice (draft): removal 50.38 929 6045
invoice accept: apply 41.44 25904 4973
invoice accept: cancel 45.86 1197 5503
customer reserve: creation 3827.78 247 459333
customer reserve: removal 4459.38 98 535126
realization accept: apply 81.38 288 9766
realization accept: cancel 81.47 177 9776
payment from customer: creation 57.98 166 6957
payment from customer: removal 5.43 154 652
payment to supplier: creation 47.44 190 5693
payment to supplier: removal 3.41 115 409
service: total inventory turnovers 3.85 4171 462
service: total monetary turnovers 7.67 404 920
service: aggregate perf. data 0.88 17539 106
service: refresh index statistics 0.04 354216 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-02-2023 10:38:36 to 22-02-2023 10:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 14704
TEST_TIME 2 10249
TEST_TIME 3 8835
TEST_TIME 4 8806
TEST_TIME 5 8255
TEST_TIME 6 7941
TEST_TIME 7 7700
TEST_TIME 8 7265
TEST_TIME 9 7485
TEST_TIME 10 7398
TEST_TIME 11 7509
TEST_TIME 12 7457
TEST_TIME 13 7403
TEST_TIME 14 7358
TEST_TIME 15 7229
TEST_TIME 16 7384
TEST_TIME 17 7385
TEST_TIME 18 7540
TEST_TIME 19 7717
TEST_TIME 20 7851
TEST_TIME 21 8158
TEST_TIME 22 8241
TEST_TIME 23 8342
TEST_TIME 24 8276
TEST_TIME 25 8641
TEST_TIME 26 8774
TEST_TIME 27 8989
TEST_TIME 28 9127
TEST_TIME 29 9545
TEST_TIME 30 9870
TEST_TIME 31 9884
TEST_TIME 32 10301
TEST_TIME 33 10934
TEST_TIME 34 11091
TEST_TIME 35 10822
TEST_TIME 36 10918
TEST_TIME 37 10923
TEST_TIME 38 10707
TEST_TIME 39 10676
TEST_TIME 40 10616
TEST_TIME 41 10486
TEST_TIME 42 10462
TEST_TIME 43 10533
TEST_TIME 44 10418
TEST_TIME 45 10279
TEST_TIME 46 10174
TEST_TIME 47 10175
TEST_TIME 48 10156
TEST_TIME 49 10164
TEST_TIME 50 10092
TEST_TIME 51 10250
TEST_TIME 52 10215
TEST_TIME 53 10026
TEST_TIME 54 9888
TEST_TIME 55 9804
TEST_TIME 56 9669
TEST_TIME 57 9757
TEST_TIME 58 9732
TEST_TIME 59 9681
TEST_TIME 60 9614
TEST_TIME 61 9674
TEST_TIME 62 9637
TEST_TIME 63 9617
TEST_TIME 64 9607
TEST_TIME 65 9562
TEST_TIME 66 9516
TEST_TIME 67 9495
TEST_TIME 68 9450
TEST_TIME 69 9441
TEST_TIME 70 9439
TEST_TIME 71 9399
TEST_TIME 72 9370
TEST_TIME 73 9335
TEST_TIME 74 9336
TEST_TIME 75 9339
TEST_TIME 76 9465
TEST_TIME 77 9432
TEST_TIME 78 9429
TEST_TIME 79 9539
TEST_TIME 80 9523
TEST_TIME 81 9499
TEST_TIME 82 9481
TEST_TIME 83 9393
TEST_TIME 84 9311
TEST_TIME 85 9280
TEST_TIME 86 9255
TEST_TIME 87 9226
TEST_TIME 88 9179
TEST_TIME 89 9111
TEST_TIME 90 9105
TEST_TIME 91 9069
TEST_TIME 92 9136
TEST_TIME 93 9122
TEST_TIME 94 9109
TEST_TIME 95 9093
TEST_TIME 96 9084
TEST_TIME 97 9112
TEST_TIME 98 9086
TEST_TIME 99 9132
TEST_TIME 100 9101
TEST_TIME 101 9213
TEST_TIME 102 9249
TEST_TIME 103 9246
TEST_TIME 104 9177
TEST_TIME 105 9143
TEST_TIME 106 9100
TEST_TIME 107 9085
TEST_TIME 108 9062
TEST_TIME 109 9023
TEST_TIME 110 8994
TEST_TIME 111 9034
TEST_TIME 112 9025
TEST_TIME 113 9022
TEST_TIME 114 9008
TEST_TIME 115 9021
TEST_TIME 116 9020
TEST_TIME 117 9037
TEST_TIME 118 9091
TEST_TIME 119 9058
TEST_TIME 120 9011

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-02-2023 10:38:40 to 22-02-2023 10:38:43.

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 1499896 1499896 0 0.00 0 14368 13 0 0
doc_list_biud 1499881 1499881 0 0.00 0 42806 41 0 0
sp_add_invoice_to_stock 5072 4973 99 1.95 19 231153 25905 0 99
sp_cancel_adding_invoice 5503 5503 0 0.00 4 96982 1198 0 0
sp_cancel_client_order 1768 1768 0 0.00 5 10086 389 0 0
sp_cancel_customer_reserve 535126 535126 0 0.00 1 21523 98 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 4 11162 154 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 4 2319 115 0 0
sp_cancel_supplier_invoice 6052 6045 7 0.11 2 14273 929 0 7
sp_cancel_supplier_order 381 381 0 0.00 8 1406831 172615 0 0
sp_cancel_write_off 9776 9776 0 0.00 2 42818 178 0 0
sp_client_order 19586 19586 0 0.00 6 64447 486 0 0
sp_customer_reserve 459333 459333 0 0.00 1 160328 248 0 0
sp_fill_shopping_cart 30326 30326 0 0.00 1 13180 84 0 0
sp_fill_shopping_cart_clo_res 459292 459292 0 0.00 0 10522 19 0 0
sp_get_clo_for_invoice 4923 4923 0 0.00 0 18184 297 0 0
sp_kill_cost_storno 16340 16340 0 0.00 0 11155 72 0 0
sp_kill_qstorno_ret_qs2qd 1086454 1086454 0 0.00 0 42806 43 0 0
sp_kill_qty_storno 573284 573284 0 0.00 0 42806 98 0 0
sp_lock_selected_doc 20322 20322 0 0.00 0 7895 7 0 0
sp_make_cost_storno 27339 27339 0 0.00 0 11579 78 0 0
sp_multiply_rows_for_pdistr 27337 27337 0 0.00 0 7951 38 0 0
sp_multiply_rows_for_qdistr 19586 19586 0 0.00 0 64437 190 0 0
sp_pay_from_customer 6957 6957 0 0.00 5 10986 166 0 0
sp_pay_to_supplier 5693 5693 0 0.00 6 18006 191 0 0
sp_payment_common 12649 12649 0 0.00 2 17938 45 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 10059 314 0 0
sp_qd_handle_on_invoice_upd_sts 10424 10424 0 0.00 0 12686 505 0 0
sp_qd_handle_on_reserve_upd_sts 9764 9764 0 0.00 0 17215 76 0 0
sp_reserve_write_off 9766 9766 0 0.00 8 17249 289 0 0
sp_supplier_invoice 5393 5393 0 0.00 7 59183 3138 0 0
sp_supplier_order 5362 5362 0 0.00 8 33035 1341 0 0
srv_aggregate_perf_data 106 106 0 0.00 2691 177940 17539 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 658 176977 35678 0 0
srv_find_qd_qs_mism 1029750 1029750 0 0.00 0 9141 9 0 0
srv_make_invnt_saldo 462 462 0 0.00 30 53631 4172 0 0
srv_make_money_saldo 920 920 0 0.00 1 9773 404 0 0
srv_recalc_idx_stat 5 5 0 0.00 216623 502979 354216 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4975 4975 0 0.00 1 10964 922 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 1 6737 470 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 3460 32 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 2416 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 572 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 220 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 355 355 0 0.00 0 1069 39 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 83641 6270 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 973 15 0 0
t$perf-norm:sp_client_order 19588 19588 0 0.00 0 9278 32 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 741 14 0 0
t$perf-norm:sp_pay_from_customer 6957 6957 0 0.00 0 2558 16 0 0
t$perf-norm:sp_pay_to_supplier 5693 5693 0 0.00 0 2985 18 0 0
t$perf-norm:sp_reserve_write_off 9766 9766 0 0.00 0 10303 22 0 0
t$perf-norm:sp_supplier_invoice 5393 5393 0 0.00 0 3638 30 0 0
t$perf-norm:sp_supplier_order 5362 5362 0 0.00 0 10118 32 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 622 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 102 5 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 576 9 0 0
t$perf-norm:srv_make_money_saldo 920 920 0 0.00 0 3144 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 189 83 0 0
v_add_invoice_to_stock 10034 9836 198 1.97 0 9000 26 0 198
v_all_customers 35840 35840 0 0.00 0 120 4 0 0
v_all_suppliers 29649 29649 0 0.00 0 75 4 0 0
v_all_wares 39167 39167 0 0.00 0 62 4 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 3610 25 0 0
v_cancel_client_order 3536 3536 0 0.00 0 1960 11 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 2481 16 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 2268 14 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 1516 14 0 0
v_cancel_supplier_invoice 724 710 14 1.93 0 1486 26 0 14
v_cancel_supplier_order 762 762 0 0.00 0 3607 21 0 0
v_cancel_write_off 1968 1968 0 0.00 0 2128 27 0 0
v_max_id_clo_ord 5360 5360 0 0.00 0 693 5 0 0
v_max_id_clo_res 991 991 0 0.00 0 2602 17 0 0
v_max_id_ord_sup 5380 5380 0 0.00 0 6277 9 0 0
v_max_non_paid_invoice 1606 1606 0 0.00 0 59 8 0 0
v_max_non_paid_realizn 3617 3617 0 0.00 0 717 8 0 0
v_min_id_clo_ord 5360 5360 0 0.00 0 3167 9 0 0
v_min_id_clo_res 991 991 0 0.00 0 256 8 0 0
v_min_id_ord_sup 5380 5380 0 0.00 0 5460 10 0 0
v_min_non_paid_invoice 5692 5692 0 0.00 0 17929 19 0 0
v_min_non_paid_realizn 6955 6955 0 0.00 0 4559 15 0 0
v_our_firm 10004 10004 0 0.00 0 117 5 0 0
v_reserve_write_off 19526 19526 0 0.00 0 3875 12 0 0
x_lock_depdocs_on_canc_invoice 11193 11193 0 0.00 0 23765 129 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 12609 431 0 0
x_make_qstorno_1000_1200 5362 5362 0 0.00 2 33014 1172 0 0
x_make_qstorno_1200_2000 5393 5393 0 0.00 3 59161 2912 0 0
x_make_qstorno_2100_3300 459332 459332 0 0.00 0 160314 216 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 22-02-2023 10:38:44 to 22-02-2023 10:38:49.

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
08:07:29 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:30 24217 8192000000 9711829312 10006343680 787106816 8.765 100 90 178 1 816612800 7629312 21876672
08:09:58 19254 8192000000 9752254800 10062565376 829992080 9.199 100 88 176 0 862662608 10989744 21680784
08:11:32 13238 8192000000 9806936080 10126913536 865080992 9.551 100 79 158 0 916380736 32995136 18304608
08:12:52 7807 8192000000 9848930240 10184204288 913837920 10.035 100 84 164 0 964722256 32952512 17931824
08:14:06 3511 8192000000 9914247536 10255728640 961862864 10.507 100 84 167 0 1024314544 44986512 17465168
08:15:12 2030 8192000000 9957208224 10310455296 993696288 10.817 100 90 174 0 1081551632 69854896 18000448
08:16:16 1006 8192000000 10004711152 10368991232 1022932240 11.100 100 94 179 0 1119423168 79480512 17010416
08:17:19 1599 8192000000 10008505904 10376802304 1041999472 11.284 100 91 172 2 1129458768 71212704 16246592
08:18:21 992 8192000000 10046775904 10421719040 1056446304 11.422 100 95 185 2 1163840672 88405152 18989216
08:19:24 1017 8192000000 10041317600 10420494336 1069544960 11.548 100 95 181 0 1159868000 72092480 18230560
08:20:29 1061 8192000000 10055799216 10434260992 1068795216 11.541 100 96 188 1 1176450240 89493088 18161936
08:21:32 1929 8192000000 10071133552 10455846912 1077256144 11.621 100 82 152 0 1192668896 100251024 15161728
08:22:34 4520 8192000000 10021244304 10414010368 1081814720 11.665 100 64 120 0 1142645520 48795536 12035264
08:23:40 1037 8192000000 10057541344 10447990784 1087101456 11.715 100 95 176 3 1180804688 75539856 18163376
08:24:41 1123 8192000000 10057149824 10448707584 1092314880 11.765 100 76 152 1 1179155712 71374432 15466400
08:25:44 1270 8192000000 10058023488 10445295616 1092738304 11.769 100 93 174 0 1177485424 66610288 18136832
08:26:46 1173 8192000000 10070610448 10459680768 1097685312 11.816 100 95 185 1 1194493280 79445136 17362832
08:27:48 943 8192000000 10050290080 10441416704 1095962544 11.799 100 84 161 1 1173190144 60446656 16780944
08:28:50 989 8192000000 10058758896 10447863808 1084910432 11.694 100 94 184 2 1181036208 77294384 18831392
08:29:52 914 8192000000 10026303456 10415345664 1093144080 11.773 100 90 169 1 1146486240 35500528 17841632
08:30:53 5855 8192000000 10038669696 10433585152 1087559968 11.719 100 62 112 2 1158612992 58358944 12694080
08:32:00 1033 8192000000 9996884256 10373943296 1056454256 11.423 100 84 153 1 1114253456 42103488 15695712
08:33:03 1000 8192000000 10022757584 10403311616 1053600384 11.395 99 95 179 3 1136732704 65304768 17827552
08:34:05 1189 8192000000 9992537360 10361802752 1037940400 11.245 100 96 179 1 1111656944 55828272 17888272
08:35:07 938 8192000000 9994492080 10369032192 1036950160 11.235 100 97 189 1 1118290176 61515600 19824416
08:36:09 1076 8192000000 9984254768 10356142080 1043473008 11.298 100 91 180 4 1104088912 42652464 17963440
08:37:11 1567 8192000000 9999028176 10379472896 1037652656 11.242 100 83 151 2 1124237632 70653680 15931296
08:38:17 1598 8192000000 10019210160 10397917184 1057501056 11.433 100 97 182 3 1140656032 63084544 20070432
08:39:25 4410 8192000000 10018240432 10395152384 1065428240 11.508 100 84 156 2 1135446256 53246064 16771952
08:40:33 2216 8192000000 10048699488 10430230528 1072515712 11.576 100 88 165 1 1155781568 66171856 17094000
08:41:43 3880 8192000000 10089120352 10476060672 1082435696 11.671 100 83 161 1 1204331280 105233824 16661760
08:42:50 2421 8192000000 10079746224 10463592448 1077906304 11.628 99 82 161 2 1186072016 91177696 16988016
08:43:57 1983 8192000000 10119205312 10503626752 1097247888 11.812 100 85 160 2 1223042912 109842096 15952928
08:45:04 1552 8192000000 10146649904 10541613056 1120160128 12.029 100 74 146 0 1267068528 130788368 16120032
08:46:14 2398 8192000000 10150704192 10556612608 1127972016 12.102 100 62 122 1 1273768112 132439440 13356656
08:47:20 1026 8192000000 10210214624 10601984000 1147814976 12.289 100 96 187 0 1326611376 156444000 22352400
08:48:25 715 8192000000 10239281360 10640072704 1158176480 12.386 100 100 196 1 1349281856 168402320 22703056
08:49:29 855 8192000000 10290245616 10681712640 1168614240 12.484 100 91 174 1 1400533712 212445056 19474416
08:50:33 2587 8192000000 10322261632 10722852864 1180712432 12.597 100 88 173 0 1434621920 233469792 20439696
08:51:39 1044 8192000000 10388219056 10788655104 1185443648 12.641 100 98 191 1 1495440272 288471040 21525584
08:52:44 945 8192000000 10396061488 10792153088 1172021776 12.516 99 95 185 0 1502023760 309082688 20919296
08:53:49 1130 8192000000 10452855776 10864738304 1194868368 12.729 100 97 190 3 1559522992 341800736 22853888
08:54:53 1693 8192000000 10474764000 10896691200 1188599504 12.670 100 90 178 0 1580960432 370739440 21621488
08:55:57 651 8192000000 10492914336 10916470784 1187350704 12.659 100 98 193 0 1598388400 388188560 22849136
08:56:59 1064 8192000000 10504006464 10942693376 1189826176 12.682 100 96 190 3 1607190224 396050880 21313168
08:58:02 727 8192000000 10518273184 10938626048 1182237824 12.611 100 97 187 0 1626743648 421904336 22601488
08:59:05 1050 8192000000 10530250336 10937651200 1177223968 12.564 100 99 193 0 1635960912 435420352 23316592
09:00:10 739 8192000000 10553117488 10971586560 1188585344 12.670 100 94 184 1 1659385568 448078000 22722224
09:01:14 4407 8192000000 10577997184 11019784192 1186938960 12.655 100 69 138 0 1677787568 473809888 17038720
09:02:25 1765 8192000000 10587908784 11020693504 1194322064 12.724 100 90 178 1 1692951392 477546672 21082656
09:03:30 9453 8192000000 10564453376 11007344640 1192524240 12.707 100 55 110 0 1656130032 449221520 14384272
09:04:52 20592 8192000000 10503805840 10992906240 1184508256 12.632 100 37 74 0 1634672720 440801696 9362768
09:06:53 24883 8192000000 10440722624 10941161472 1174819456 12.542 100 35 70 0 1555033984 371119072 9095456
09:08:23 22503 8192000000 10400623024 10868183040 1164266528 12.443 100 28 53 1 1506539536 335155952 7117056
09:09:46 33525 8192000000 10256690416 10838425600 1160496128 12.408 100 26 51 0 1396295472 229431920 6367424
09:11:34 26427 8192000000 10135352912 10589069312 1154554048 12.352 100 27 54 0 1233987872 73181888 6251936
09:13:01 43336 8192000000 10129314896 10636718080 1158516160 12.389 100 24 47 0 1245525008 81975248 5033600
09:15:17 87401 8192000000 10122013520 10602094592 1114579200 11.976 97 14 28 1 1191820144 74169920 3071024
09:17:48 11439 8192000000 10106592032 10540453888 1148687264 12.297 100 58 110 1 1216977920 53249248 15041408
09:19:01 125695 8192000000 10069929360 10527023104 1098347168 11.822 95 17 34 0 1169772720 67895584 3529968
09:22:24 1654 8192000000 10062835088 10482249728 1119214000 12.020 100 85 161 2 1152106432 16372912 16519520
09:23:29 18648 8192000000 10109486144 10531565568 1138487008 12.201 100 71 128 2 1197456624 45236000 13733616
09:24:49 67700 8192000000 10073103344 10492321792 1076540016 11.614 94 33 59 4 1098756704 14999856 7216832
09:27:02 176977 8192000000 10091922704 10636537856 1077459712 11.623 94 8 16 0 1175412352 96222640 1730000
09:31:59 54019 8192000000 10207326496 10660499456 1141665760 12.231 99 51 101 1 1247623920 94524912 11433248
09:33:55 104400 8192000000 10155775456 10607476736 1145385856 12.266 99 44 78 0 1256040672 101310000 9344816
09:37:37 106860 8192000000 10146849776 10672480256 1138738720 12.204 99 15 30 0 1235850304 93450832 3660752
09:40:34 118308 8192000000 10112023200 10563096576 1125191920 12.076 98 21 43 0 1240306640 110462688 4652032
09:43:33 22566 8192000000 10070074432 10511077376 1134012752 12.159 99 22 41 0 1181113056 43318944 3781360
09:44:58 60441 8192000000 10083049200 10551742464 1134512384 12.164 99 8 16 0 1168608784 32378128 1718272
09:46:59 73921 8192000000 10072654688 10519584768 1108814448 11.921 97 13 26 1 1173545040 61727216 3003376
09:49:27 11904 8192000000 10125211680 10555715584 1142623712 12.240 100 60 118 0 1215031968 60237296 12170960
09:50:43 24485 8192000000 10125669664 10565963776 1100119776 11.839 96 26 52 1 1179494496 73816176 5558544
09:52:08 41717 8192000000 10135064608 10580328448 1133364064 12.153 100 16 30 0 1232453408 95594336 3495008
09:53:54 41552 8192000000 10093392848 10542424064 1119449648 12.022 98 33 66 0 1159383744 33066448 6867648
09:55:38 35091 8192000000 10120443552 10617049088 1130762544 12.129 99 36 70 1 1259766112 121906784 7096784
09:57:45 45256 8192000000 10125694752 10563809280 1126309344 12.087 99 29 58 0 1173941296 42132160 5499792
09:59:34 108380 8192000000 10069241072 10526789632 1127377552 12.097 99 18 36 0 1166040928 35239520 3423856
10:02:42 63258 8192000000 10097983088 10569007104 1141729152 12.232 100 12 24 1 1205434608 61211392 2494064
10:04:46 46636 8192000000 10109508416 10584879104 1140384160 12.219 100 21 40 1 1197178640 52341232 4453248
10:06:33 67151 8192000000 10151530384 10610032640 1110680320 11.939 97 10 20 0 1222159728 109068352 2411056
10:08:45 94678 8192000000 10065236224 10524946432 1111888512 11.950 98 16 33 0 1187844944 72227168 3729264
10:11:20 28748 8192000000 10088366320 10529796096 1115633248 11.986 98 17 34 0 1173890560 54568608 3688704
10:12:59 21839 8192000000 10100822368 10541551616 1082349888 11.670 96 44 88 0 1176073728 84547840 9176000
10:14:27 78770 8192000000 10136017936 10585632768 1125820976 12.082 100 29 57 0 1210286656 78676736 5788944
10:16:57 21915 8192000000 10159568576 10589528064 1127510720 12.098 99 36 67 1 1232287504 97664160 7112624
10:18:21 47922 8192000000 10111909888 10553438208 1110457040 11.937 98 14 28 0 1201809952 88608448 2744464
10:20:09 19986 8192000000 10099197200 10547556352 1126078608 12.084 100 36 71 0 1208741808 75558816 7104384
10:21:30 69957 8192000000 10083406720 10525200384 1126455328 12.088 100 53 101 0 1187371568 51015808 9900432
10:23:43 20526 8192000000 10067516944 10492899328 1101263392 11.850 98 54 104 0 1158799008 47297024 10238592
10:25:04 63841 8192000000 10062427072 10514079744 1105574864 11.891 98 13 24 0 1161287888 53428768 2284256
10:27:08 37712 8192000000 10094580816 10578817024 1117203488 12.001 99 5 10 0 1182201472 64126432 871552
10:28:55 112742 8192000000 10091054400 10547023872 1080268512 11.650 95 12 23 0 1125476960 43108848 2099600
10:31:48 51626 8192000000 10124228032 10588733440 1106167968 11.896 97 37 74 0 1210596320 96465104 7963248
10:33:40 32465 8192000000 10116631584 10564960256 1098949712 11.828 97 19 36 1 1181931632 79043024 3938896
10:35:14 36664 8192000000 10087618560 10553675776 1101467824 11.852 98 31 60 1 1179449312 71659344 6322144
10:37:22 20720 8192000000 9083271280 9381355520 209581312 2.494 19 8 15 0 258609152 47334816 1693024

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-02-2023 10:38:51 to 22-02-2023 10:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-02-2023 10:39:08 to 22-02-2023 10:39:10.

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          221738
MON$OLDEST_ACTIVE               221739
MON$OLDEST_SNAPSHOT             221739
MON$NEXT_TRANSACTION            221739
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                       2071136
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             781
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 ---
:127: 
:128: mscs-test-161.budzdorov.ru    Wed Feb 22 10:38:06 2023
:129:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:130: 
--- end of diff output ---

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

22.02.23 10:39:11. 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-22 10:39:14 No severe PSQL-related problems occured
22.02.23 10:39:15. End of report.