Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.03.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 14692504 kB
MemAvailable 30384948 kB
Buffers 0 kB
Cached 16966680 kB
SwapCached 3168 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 10:37:28
DTS_END                         2023-03-17 10:37:28.1330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47516
TRN_ID                          217907
ATT_ID                          630
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: 9188.51 23612 1102621
customer order: creation 169.68 515 20361
customer order: refuse 14.51 466 1741
order to supplier: creation 44.53 1495 5344
order to supplier: removal 3.42 209523 410
invoice (draft): creation 45.01 3163 5401
invoice (draft): removal 53.00 1109 6360
invoice accept: apply 40.23 29186 4827
invoice accept: cancel 47.50 1349 5700
customer reserve: creation 3787.28 273 454474
customer reserve: removal 4690.10 120 562812
realization accept: apply 82.46 319 9895
realization accept: cancel 81.86 187 9823
payment from customer: creation 58.40 199 7008
payment from customer: removal 5.75 138 690
payment to supplier: creation 48.48 207 5817
payment to supplier: removal 3.68 128 441
service: total inventory turnovers 3.92 4647 470
service: total monetary turnovers 7.78 475 934
service: aggregate perf. data 0.90 16515 108
service: refresh index statistics 0.04 202232 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-03-2023 10:38:42 to 17-03-2023 10:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3541
TEST_TIME 2 5622
TEST_TIME 3 5299
TEST_TIME 4 5377
TEST_TIME 5 5717
TEST_TIME 6 5599
TEST_TIME 7 5768
TEST_TIME 8 5921
TEST_TIME 9 5874
TEST_TIME 10 5798
TEST_TIME 11 6215
TEST_TIME 12 6294
TEST_TIME 13 6556
TEST_TIME 14 6639
TEST_TIME 15 6840
TEST_TIME 16 7154
TEST_TIME 17 6957
TEST_TIME 18 6825
TEST_TIME 19 7093
TEST_TIME 20 7125
TEST_TIME 21 7266
TEST_TIME 22 7318
TEST_TIME 23 7223
TEST_TIME 24 7370
TEST_TIME 25 7418
TEST_TIME 26 7433
TEST_TIME 27 7691
TEST_TIME 28 7686
TEST_TIME 29 7983
TEST_TIME 30 7950
TEST_TIME 31 8290
TEST_TIME 32 8484
TEST_TIME 33 8502
TEST_TIME 34 8572
TEST_TIME 35 9047
TEST_TIME 36 9160
TEST_TIME 37 9768
TEST_TIME 38 9997
TEST_TIME 39 10633
TEST_TIME 40 10932
TEST_TIME 41 10756
TEST_TIME 42 10697
TEST_TIME 43 10611
TEST_TIME 44 10477
TEST_TIME 45 10378
TEST_TIME 46 10305
TEST_TIME 47 10228
TEST_TIME 48 10204
TEST_TIME 49 10047
TEST_TIME 50 9952
TEST_TIME 51 9908
TEST_TIME 52 9877
TEST_TIME 53 9821
TEST_TIME 54 9798
TEST_TIME 55 9975
TEST_TIME 56 9944
TEST_TIME 57 9895
TEST_TIME 58 9898
TEST_TIME 59 9774
TEST_TIME 60 9769
TEST_TIME 61 9702
TEST_TIME 62 9721
TEST_TIME 63 9677
TEST_TIME 64 9618
TEST_TIME 65 9670
TEST_TIME 66 9736
TEST_TIME 67 9720
TEST_TIME 68 9846
TEST_TIME 69 9787
TEST_TIME 70 9779
TEST_TIME 71 9787
TEST_TIME 72 9958
TEST_TIME 73 9911
TEST_TIME 74 9889
TEST_TIME 75 9850
TEST_TIME 76 9775
TEST_TIME 77 9790
TEST_TIME 78 9786
TEST_TIME 79 9720
TEST_TIME 80 9636
TEST_TIME 81 9661
TEST_TIME 82 9639
TEST_TIME 83 9599
TEST_TIME 84 9591
TEST_TIME 85 9527
TEST_TIME 86 9492
TEST_TIME 87 9500
TEST_TIME 88 9523
TEST_TIME 89 9476
TEST_TIME 90 9461
TEST_TIME 91 9483
TEST_TIME 92 9479
TEST_TIME 93 9462
TEST_TIME 94 9455
TEST_TIME 95 9468
TEST_TIME 96 9544
TEST_TIME 97 9536
TEST_TIME 98 9567
TEST_TIME 99 9555
TEST_TIME 100 9569
TEST_TIME 101 9557
TEST_TIME 102 9522
TEST_TIME 103 9477
TEST_TIME 104 9419
TEST_TIME 105 9394
TEST_TIME 106 9391
TEST_TIME 107 9336
TEST_TIME 108 9344
TEST_TIME 109 9306
TEST_TIME 110 9248
TEST_TIME 111 9217
TEST_TIME 112 9228
TEST_TIME 113 9233
TEST_TIME 114 9238
TEST_TIME 115 9207
TEST_TIME 116 9236
TEST_TIME 117 9225
TEST_TIME 118 9220
TEST_TIME 119 9243
TEST_TIME 120 9208

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-03-2023 10:38:45 to 17-03-2023 10:38:49.

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 1521951 1521951 0 0.00 0 15201 15 0 0
doc_list_biud 1521941 1521941 0 0.00 0 22110 50 0 0
sp_add_invoice_to_stock 4918 4827 91 1.85 75 204516 29187 0 91
sp_cancel_adding_invoice 5700 5700 0 0.00 6 89455 1349 0 0
sp_cancel_client_order 1741 1741 0 0.00 6 11662 466 0 0
sp_cancel_customer_reserve 562812 562812 0 0.00 1 21707 120 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 4 3823 138 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 4 4601 129 0 0
sp_cancel_supplier_invoice 6368 6360 8 0.12 3 20259 1109 0 8
sp_cancel_supplier_order 410 410 0 0.00 11 1568605 209523 0 0
sp_cancel_write_off 9823 9823 0 0.00 2 9818 188 0 0
sp_client_order 20361 20361 0 0.00 6 29733 516 0 0
sp_customer_reserve 454474 454474 0 0.00 1 70697 273 0 0
sp_fill_shopping_cart 31100 31100 0 0.00 1 8997 85 0 0
sp_fill_shopping_cart_clo_res 454441 454441 0 0.00 0 16835 20 0 0
sp_get_clo_for_invoice 4782 4782 0 0.00 0 26264 271 0 0
sp_kill_cost_storno 16654 16654 0 0.00 0 8755 80 0 0
sp_kill_qstorno_ret_qs2qd 1142217 1142217 0 0.00 0 21078 51 0 0
sp_kill_qty_storno 601522 601522 0 0.00 0 22110 117 0 0
sp_lock_selected_doc 20456 20456 0 0.00 0 8917 8 0 0
sp_make_cost_storno 27502 27502 0 0.00 0 14963 91 0 0
sp_multiply_rows_for_pdistr 27499 27499 0 0.00 0 9124 39 0 0
sp_multiply_rows_for_qdistr 20361 20361 0 0.00 0 14257 195 0 0
sp_pay_from_customer 7008 7008 0 0.00 5 19242 200 0 0
sp_pay_to_supplier 5817 5817 0 0.00 6 13409 207 0 0
sp_payment_common 12823 12823 0 0.00 2 8496 48 0 0
sp_qd_handle_on_cancel_clo 1741 1741 0 0.00 1 11655 379 0 0
sp_qd_handle_on_invoice_upd_sts 10481 10481 0 0.00 0 22109 589 0 0
sp_qd_handle_on_reserve_upd_sts 9895 9895 0 0.00 0 10910 82 0 0
sp_reserve_write_off 9895 9895 0 0.00 9 16426 319 0 0
sp_supplier_invoice 5401 5401 0 0.00 9 81834 3163 0 0
sp_supplier_order 5344 5344 0 0.00 7 43720 1496 0 0
srv_aggregate_perf_data 108 108 0 0.00 2361 160459 16515 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 716 101169 29027 0 0
srv_find_qd_qs_mism 1054768 1054768 0 0.00 0 12441 12 0 0
srv_make_invnt_saldo 470 470 0 0.00 90 77366 4647 0 0
srv_make_money_saldo 934 934 0 0.00 1 29702 475 0 0
srv_recalc_idx_stat 5 5 0 0.00 83680 373027 202232 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4832 4832 0 0.00 1 19003 1018 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 2 6534 510 0 0
t$perf-norm:sp_cancel_client_order 1741 1741 0 0.00 0 2950 29 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 2144 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 886 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 501 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 1018 46 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 111538 8307 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 2765 24 0 0
t$perf-norm:sp_client_order 20361 20361 0 0.00 0 7759 41 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 514 14 0 0
t$perf-norm:sp_pay_from_customer 7008 7008 0 0.00 0 4521 21 0 0
t$perf-norm:sp_pay_to_supplier 5817 5817 0 0.00 0 3055 23 0 0
t$perf-norm:sp_reserve_write_off 9895 9895 0 0.00 0 10612 29 0 0
t$perf-norm:sp_supplier_invoice 5402 5402 0 0.00 0 3523 35 0 0
t$perf-norm:sp_supplier_order 5344 5344 0 0.00 0 8140 38 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 1601 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 1274 24 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 1144 21 0 0
t$perf-norm:srv_make_money_saldo 934 934 0 0.00 0 2158 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 203 85 0 0
v_add_invoice_to_stock 9738 9556 182 1.86 0 6431 30 0 182
v_all_customers 37431 37431 0 0.00 0 182 4 0 0
v_all_suppliers 30501 30501 0 0.00 0 71 5 0 0
v_all_wares 40718 40718 0 0.00 0 128 5 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 112 15 0 0
v_cancel_client_order 3482 3482 0 0.00 0 4799 13 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 1461 13 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 2930 17 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 4581 15 0 0
v_cancel_supplier_invoice 772 756 16 2.07 0 2474 34 0 16
v_cancel_supplier_order 820 820 0 0.00 0 3065 16 0 0
v_cancel_write_off 2072 2072 0 0.00 0 1890 27 0 0
v_max_id_clo_ord 5341 5341 0 0.00 0 2863 6 0 0
v_max_id_clo_res 974 974 0 0.00 0 1510 14 0 0
v_max_id_ord_sup 5398 5398 0 0.00 0 6826 10 0 0
v_max_non_paid_invoice 1301 1301 0 0.00 0 57 9 0 0
v_max_non_paid_realizn 3608 3608 0 0.00 0 287 8 0 0
v_min_id_clo_ord 5340 5340 0 0.00 0 4027 10 0 0
v_min_id_clo_res 974 974 0 0.00 0 6341 23 0 0
v_min_id_ord_sup 5398 5398 0 0.00 0 2179 8 0 0
v_min_non_paid_invoice 5815 5815 0 0.00 0 3373 14 0 0
v_min_non_paid_realizn 7008 7008 0 0.00 0 8488 19 0 0
v_our_firm 10082 10082 0 0.00 0 87 5 0 0
v_reserve_write_off 19785 19785 0 0.00 0 3509 13 0 0
x_lock_depdocs_on_canc_invoice 11682 11682 0 0.00 0 25120 146 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 11110 447 0 0
x_make_qstorno_1000_1200 5344 5344 0 0.00 2 42984 1311 0 0
x_make_qstorno_1200_2000 5401 5401 0 0.00 4 81800 2949 0 0
x_make_qstorno_2100_3300 454472 454472 0 0.00 0 70693 240 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-03-2023 10:38:50 to 17-03-2023 10:38:54.

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:32 879 8192000000 8926433184 8984162304 34791680 0.422 7 6 10 0 35867888 224128 852080
08:08:34 26084 8192000000 9718601840 10015715328 795360224 8.849 100 88 177 0 824081056 7708592 21012240
08:10:07 22699 8192000000 9763338688 10069540864 833044464 9.230 100 85 170 0 865808720 12219824 20544432
08:11:37 16589 8192000000 9792244464 10106040320 852564832 9.426 100 93 184 2 895701872 21689328 21447712
08:12:58 11971 8192000000 9839131072 10168070144 891946240 9.818 100 79 156 0 954175392 45174624 17054528
08:14:13 4607 8192000000 9902337632 10243170304 944294528 10.335 100 84 164 0 1017603312 56004640 17304144
08:15:19 2377 8192000000 9941404320 10293456896 984994960 10.733 100 84 162 3 1056413520 54329808 17088752
08:16:26 1350 8192000000 10016928416 10376298496 1013004336 11.004 100 91 181 0 1132365248 101421648 17939264
08:17:29 1389 8192000000 10020821456 10388324352 1045020656 11.313 100 92 174 1 1138286448 76170304 17095488
08:18:31 980 8192000000 10043960032 10419662848 1056730896 11.425 100 96 191 2 1164424448 88721040 18972512
08:19:34 13232 8192000000 10037192640 10426798080 1066074464 11.515 100 82 159 0 1160258752 78501792 15682496
08:20:48 975 8192000000 10034376848 10415386624 1070899488 11.561 100 90 177 1 1160193536 70683504 18610544
08:21:50 1229 8192000000 10051421776 10436927488 1074956640 11.599 100 91 174 2 1174648912 80915360 18776912
08:22:52 1149 8192000000 10042396896 10425331712 1084671744 11.692 100 98 187 2 1168469664 64443936 19353984
08:23:54 1128 8192000000 10066434608 10453807104 1087682400 11.721 100 98 183 4 1190563840 84979744 17901696
08:24:57 1297 8192000000 10069999904 10459099136 1093520560 11.776 100 96 188 1 1192969728 80881120 18568048
08:25:59 1096 8192000000 10071361616 10458656768 1098190288 11.820 100 98 190 1 1190877840 72861072 19826480
08:27:01 3302 8192000000 10066986976 10456072192 1102473184 11.861 100 71 137 1 1187922784 71050320 14399280
08:28:05 766 8192000000 10060270288 10447507456 1102315856 11.860 100 95 185 1 1181489488 58862336 20311296
08:29:07 1533 8192000000 10054986224 10444554240 1091970160 11.761 99 84 154 0 1175150512 67407328 15773024
08:30:10 1132 8192000000 10025808624 10403737600 1066726160 11.521 99 94 174 0 1150405568 65107360 18572048
08:31:13 989 8192000000 10035811216 10416185344 1058965056 11.447 99 94 179 1 1158284752 79020816 20298880
08:32:15 1198 8192000000 10065154016 10450157568 1077749936 11.626 100 95 182 0 1188222112 92855840 17616336
08:33:17 1077 8192000000 10016336912 10390892544 1059990640 11.456 100 94 179 1 1135909360 57583952 18334768
08:34:20 4068 8192000000 9980392672 10362953728 1042053648 11.284 100 64 116 3 1102106544 47786496 12266400
08:35:25 1150 8192000000 10009466752 10387181568 1052840464 11.388 100 94 178 4 1130977808 58685216 19452128
08:36:26 1553 8192000000 9992800480 10380226560 1050566224 11.366 100 80 148 4 1118401856 50456896 17378736
08:37:28 25139 8192000000 9983288192 10362839040 1052023824 11.380 99 78 148 1 1103513120 36291536 15197760
08:39:04 1242 8192000000 10053318976 10435584000 1073756432 11.588 100 93 178 0 1179595568 86569344 19269792
08:40:09 7442 8192000000 10056337104 10446680064 1082061088 11.667 100 70 134 0 1183216448 87816960 13338400
08:41:23 952 8192000000 10090608432 10474303488 1103261264 11.869 100 92 165 1 1208281264 86975984 18044016
08:42:28 7801 8192000000 10121055264 10520793088 1115680000 11.986 100 73 138 1 1233155472 103036944 14438528
08:43:41 1039 8192000000 10136964800 10527240192 1124430496 12.069 100 91 177 0 1254625312 111130080 19064736
08:44:48 1485 8192000000 10175263104 10563809280 1133141216 12.151 100 82 153 0 1284243504 135427440 15674848
08:46:00 3991 8192000000 10186047312 10587131904 1141072320 12.226 100 79 153 1 1307176048 148787424 17316304
08:47:10 11307 8192000000 10233832304 10640715776 1154752208 12.354 100 74 144 0 1349534128 179114448 15667472
08:48:34 1698 8192000000 10231970560 10644140032 1160211088 12.405 100 79 152 1 1346917296 168038192 18668016
08:49:39 1044 8192000000 10278485840 10676465664 1166058224 12.460 100 98 195 1 1393151360 203754768 23338368
08:50:42 908 8192000000 10312072752 10719879168 1168748576 12.485 100 97 188 0 1425072304 235453840 20869888
08:51:47 697 8192000000 10329240016 10751885312 1179443552 12.585 100 98 188 2 1440893296 239279280 22170464
08:52:51 1051 8192000000 10339903424 10755457024 1181238624 12.602 100 94 178 0 1452228112 251484480 19505008
08:53:55 998 8192000000 10364238112 10781196288 1186979856 12.655 100 93 175 1 1472700192 265445728 20274608
08:55:01 1131 8192000000 10430414368 10836672512 1206372528 12.835 100 97 188 2 1537531856 308006768 23152560
08:56:08 1599 8192000000 10461731760 10889383936 1197731968 12.755 99 93 177 0 1568792288 349177184 21883136
08:57:14 783 8192000000 10503905968 10912468992 1212553168 12.893 100 96 187 2 1608606080 373486544 22566368
08:58:18 828 8192000000 10550910368 10975367168 1211248320 12.881 100 96 183 0 1652460144 418459280 22752544
08:59:22 695 8192000000 10595007680 11023900672 1215487280 12.920 100 95 184 1 1697790816 460327616 21975920
09:00:28 1286 8192000000 10606999424 11039166464 1214516720 12.911 100 91 180 0 1712158944 475052768 22589456
09:01:34 1970 8192000000 10628987760 11059032064 1211864592 12.886 100 93 185 1 1738493072 503475872 23152608
09:02:40 2256 8192000000 10663207776 11078340608 1200376464 12.780 100 96 186 0 1762395024 538945328 23073232
09:03:48 1213 8192000000 10669637408 11119800320 1211712352 12.885 100 97 193 0 1770046896 534648368 23686176
09:04:52 761 8192000000 10688531392 11142443008 1211346016 12.882 100 98 191 1 1794274480 558145056 24783408
09:06:00 5086 8192000000 10697498656 11140599808 1200572576 12.782 99 72 144 1 1797580544 578529616 18478352
09:07:22 2739 8192000000 10650930192 11111030784 1200693632 12.783 100 75 151 0 1764006176 544210960 19101584
09:08:30 10073 8192000000 10672555856 11144527872 1195870112 12.738 100 64 128 1 1778308816 565529552 16909152
09:09:43 5640 8192000000 10653736976 11129651200 1196851568 12.747 100 44 88 0 1754144256 545906688 11386000
09:11:08 11868 8192000000 10679860400 11130040320 1185200672 12.639 100 39 74 0 1776254944 581036976 10017296
09:12:24 11893 8192000000 10567395008 11046633472 1169827744 12.495 100 32 64 1 1684931408 506409536 8694128
09:13:51 11820 8192000000 10519246992 10988249088 1172160048 12.517 100 40 80 0 1652353072 469282192 10910832
09:15:15 33702 8192000000 10277923712 10806919168 1144498208 12.258 99 34 68 0 1418538704 265868928 8171568
09:17:31 62458 8192000000 10059703456 10500403200 1087133376 11.715 95 28 52 1 1130779296 37148000 6497920
09:19:39 61602 8192000000 10078737200 10525564928 1101083408 11.848 97 21 42 0 1133484896 28773104 3628384
09:21:41 101169 8192000000 10078757536 10550751232 1112029552 11.952 98 38 73 1 1154799920 35347536 7422832
09:24:23 31277 8192000000 10089967232 10535972864 1143465504 12.248 100 38 76 1 1178078976 27108688 7504784
09:25:56 92142 8192000000 10077636496 10524192768 1102463424 11.861 96 24 46 1 1151210000 43574512 5172064
09:28:55 37189 8192000000 10124689136 10579050496 1134989520 12.168 100 41 80 0 1255207376 111692528 8525328
09:30:36 8556 8192000000 10166071840 10580590592 1130299792 12.124 100 83 161 0 1256951952 108465216 18186944
09:31:45 17643 8192000000 10216455760 10662182912 1142734528 12.241 100 62 122 1 1326635696 170518464 13382704
09:33:08 56148 8192000000 10101589632 10581643264 1126498048 12.088 99 18 36 0 1213768976 83438672 3832256
09:35:32 48129 8192000000 10092459968 10560794624 1122418704 12.050 99 17 33 1 1184879232 58633296 3827232
09:37:20 38843 8192000000 10053038896 10561650688 1126830544 12.091 100 21 42 1 1197331392 66355232 4145616
09:39:11 64214 8192000000 10076866816 10566819840 1116819152 11.997 98 14 28 0 1176521104 57019024 2682928
09:41:30 55409 8192000000 10138269120 10596765696 1120421136 12.031 98 20 38 0 1229634448 104739264 4474048
09:43:30 51039 8192000000 10147891968 10591850496 1128628736 12.108 99 22 42 0 1233869920 100624368 4616816
09:45:25 84649 8192000000 10142561600 10608549888 1106728016 11.901 97 24 48 0 1187291712 75287168 5276528
09:47:50 40032 8192000000 10167045312 10628829184 1139737264 12.213 99 37 74 1 1248275936 100948352 7590320
09:50:05 76343 8192000000 10115038592 10558377984 1133414048 12.154 99 37 70 0 1176908736 36185184 7309504
09:52:21 97618 8192000000 10109549760 10565611520 1114752512 11.977 97 36 71 0 1175576736 53479936 7344288
09:55:01 78584 8192000000 10091030144 10582122496 1119933328 12.026 98 34 66 0 1180630608 53159280 7538000
09:57:27 14690 8192000000 10137183312 10565513216 1137779616 12.195 100 69 136 0 1227887200 75097760 15009824
09:58:44 43715 8192000000 10096499536 10567958528 1136186736 12.180 100 22 42 1 1215138480 74165712 4786032
10:00:36 41158 8192000000 10094035056 10571943936 1129348496 12.115 100 18 36 0 1221999008 88685408 3965104
10:02:27 51176 8192000000 10096682320 10559193088 1104574192 11.881 97 23 44 0 1176344896 67407024 4363680
10:04:19 38277 8192000000 10082422240 10583494656 1119543904 12.023 99 15 29 0 1206442512 83307952 3590656
10:05:57 21847 8192000000 10057821168 10496262144 1128599744 12.108 100 46 90 3 1154452192 16912560 8939888
10:07:20 17294 8192000000 10101922384 10544381952 1124552496 12.070 99 42 84 0 1179319632 46169680 8597456
10:08:39 17346 8192000000 10087753984 10537455616 1122242064 12.048 99 48 95 2 1200286768 68613296 9431408
10:09:58 12497 8192000000 10102531552 10530914304 1114680752 11.977 99 49 98 0 1186681488 62581968 9418768
10:11:21 24950 8192000000 10112638768 10544349184 1118044000 12.009 98 48 96 1 1188542000 61187264 9310736
10:12:49 34221 8192000000 10178730256 10635153408 1134256000 12.161 99 50 98 0 1248971184 104796544 9918640
10:14:28 95883 8192000000 10084578144 10585411584 1068147168 11.534 94 15 30 0 1139906192 68553952 3205072
10:17:06 35674 8192000000 10088254192 10545819648 1100341792 11.841 97 18 36 0 1143512576 39829360 3341424
10:18:43 36206 8192000000 10058464672 10513920000 1064555056 11.500 95 32 64 0 1130062848 59344464 6163328
10:20:55 55659 8192000000 10051106112 10510802944 1106042032 11.895 99 30 60 1 1162697120 50213280 6441808
10:22:51 54652 8192000000 10060872240 10556915712 1122340512 12.049 100 14 28 0 1184186064 59180144 2665408
10:24:46 35213 8192000000 10053030208 10528100352 1130077168 12.122 100 18 36 0 1174069568 40176496 3815904
10:26:22 73876 8192000000 10096499520 10552463360 1117479744 12.003 98 23 44 0 1156863168 33862304 5521120
10:29:05 60347 8192000000 10069846656 10528423936 1079818992 11.646 95 10 20 0 1165017888 83000320 2198576
10:31:05 46298 8192000000 10096672432 10574692352 1136167472 12.179 100 31 57 0 1187066352 44144144 6754736
10:32:54 16234 8192000000 10153455824 10603057152 1146111712 12.273 100 48 90 0 1238542672 82571712 9859248
10:34:22 47453 8192000000 10149930192 10609856512 1141740128 12.232 99 27 51 0 1249100112 101684832 5675152
10:36:32 26810 8192000000 10173847616 10649935872 1147775904 12.289 100 39 74 1 1284802480 128874864 8151712

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 10:38:56 to 17-03-2023 10:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-03-2023 10:39:14 to 17-03-2023 10:39:16.

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          213086
MON$OLDEST_ACTIVE               218024
MON$OLDEST_SNAPSHOT             218024
MON$NEXT_TRANSACTION            218024
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                       2095360
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             770
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 ---
:135: 
:136: mscs-test-161.budzdorov.ru    Fri Mar 17 10:37:34 2023
:137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:138: 
:139: 
:140: mscs-test-161.budzdorov.ru    Fri Mar 17 10:37:37 2023
:141:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:142: 
:143: 
:144: mscs-test-161.budzdorov.ru    Fri Mar 17 10:37:38 2023
:145:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:146: 
--- end of diff output ---

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

17.03.23 10:39:17. 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 10:39:20 No severe PSQL-related problems occured
17.03.23 10:39:21. End of report.