Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.07.2022 07: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 15814684 kB
MemAvailable 31157508 kB
Buffers 0 kB
Cached 15915612 kB
SwapCached 2524 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.2
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 2022-07-08 07:38:08
DTS_END                         2022-07-08 07:37:16.2320
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58944
TRN_ID                          225812
ATT_ID                          259
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: 8695.52 21582 1043462
customer order: creation 176.36 471 21163
customer order: refuse 15.28 406 1834
order to supplier: creation 46.30 1302 5556
order to supplier: removal 3.38 155271 406
invoice (draft): creation 46.98 3143 5638
invoice (draft): removal 45.43 1032 5451
invoice accept: apply 42.20 26501 5064
invoice accept: cancel 41.18 1183 4942
customer reserve: creation 3907.57 253 468908
customer reserve: removal 4083.40 103 490008
realization accept: apply 84.42 285 10130
realization accept: cancel 69.18 188 8302
payment from customer: creation 61.54 196 7385
payment from customer: removal 6.08 125 729
payment to supplier: creation 49.48 195 5937
payment to supplier: removal 3.64 106 437
service: total inventory turnovers 4.44 3547 533
service: total monetary turnovers 7.69 313 923
service: aggregate perf. data 0.93 17092 112
service: refresh index statistics 0.03 219934 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-07-2022 07:39:40 to 08-07-2022 07:39:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 13211
TEST_TIME 2 8523
TEST_TIME 3 6916
TEST_TIME 4 6205
TEST_TIME 5 6013
TEST_TIME 6 6746
TEST_TIME 7 6899
TEST_TIME 8 6865
TEST_TIME 9 7179
TEST_TIME 10 7188
TEST_TIME 11 7298
TEST_TIME 12 7220
TEST_TIME 13 7334
TEST_TIME 14 7907
TEST_TIME 15 7929
TEST_TIME 16 8234
TEST_TIME 17 8346
TEST_TIME 18 8348
TEST_TIME 19 8635
TEST_TIME 20 8757
TEST_TIME 21 8799
TEST_TIME 22 8802
TEST_TIME 23 9092
TEST_TIME 24 9323
TEST_TIME 25 9366
TEST_TIME 26 9614
TEST_TIME 27 9980
TEST_TIME 28 10273
TEST_TIME 29 10442
TEST_TIME 30 10696
TEST_TIME 31 10698
TEST_TIME 32 10728
TEST_TIME 33 10761
TEST_TIME 34 11073
TEST_TIME 35 10805
TEST_TIME 36 10628
TEST_TIME 37 10629
TEST_TIME 38 10466
TEST_TIME 39 10402
TEST_TIME 40 10583
TEST_TIME 41 10501
TEST_TIME 42 10460
TEST_TIME 43 10483
TEST_TIME 44 10368
TEST_TIME 45 10214
TEST_TIME 46 10221
TEST_TIME 47 10259
TEST_TIME 48 10083
TEST_TIME 49 9971
TEST_TIME 50 9904
TEST_TIME 51 9782
TEST_TIME 52 9675
TEST_TIME 53 9625
TEST_TIME 54 9590
TEST_TIME 55 9566
TEST_TIME 56 9463
TEST_TIME 57 9494
TEST_TIME 58 9379
TEST_TIME 59 9300
TEST_TIME 60 9246
TEST_TIME 61 9158
TEST_TIME 62 9118
TEST_TIME 63 9084
TEST_TIME 64 9050
TEST_TIME 65 8989
TEST_TIME 66 8943
TEST_TIME 67 8921
TEST_TIME 68 8894
TEST_TIME 69 8904
TEST_TIME 70 8931
TEST_TIME 71 8982
TEST_TIME 72 9100
TEST_TIME 73 9263
TEST_TIME 74 9224
TEST_TIME 75 9186
TEST_TIME 76 9288
TEST_TIME 77 9278
TEST_TIME 78 9198
TEST_TIME 79 9134
TEST_TIME 80 9045
TEST_TIME 81 9072
TEST_TIME 82 9039
TEST_TIME 83 9033
TEST_TIME 84 8985
TEST_TIME 85 8966
TEST_TIME 86 8943
TEST_TIME 87 8954
TEST_TIME 88 8920
TEST_TIME 89 8896
TEST_TIME 90 8935
TEST_TIME 91 8894
TEST_TIME 92 8919
TEST_TIME 93 8964
TEST_TIME 94 8923
TEST_TIME 95 8917
TEST_TIME 96 8905
TEST_TIME 97 8925
TEST_TIME 98 8944
TEST_TIME 99 8909
TEST_TIME 100 8869
TEST_TIME 101 8841
TEST_TIME 102 8818
TEST_TIME 103 8760
TEST_TIME 104 8759
TEST_TIME 105 8745
TEST_TIME 106 8723
TEST_TIME 107 8716
TEST_TIME 108 8677
TEST_TIME 109 8696
TEST_TIME 110 8671
TEST_TIME 111 8677
TEST_TIME 112 8703
TEST_TIME 113 8758
TEST_TIME 114 8730
TEST_TIME 115 8705
TEST_TIME 116 8747
TEST_TIME 117 8772
TEST_TIME 118 8772
TEST_TIME 119 8787
TEST_TIME 120 8718

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-07-2022 07:39:44 to 08-07-2022 07:39:47.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1475485 1475485 0 0.00 0 19055 14 0 0
doc_list_biud 1475466 1475466 0 0.00 0 20952 41 0 0
sp_add_invoice_to_stock 5195 5064 131 2.52 33 205979 26501 0 131
sp_cancel_adding_invoice 4942 4942 0 0.00 7 82221 1183 0 0
sp_cancel_client_order 1834 1834 0 0.00 5 10821 407 0 0
sp_cancel_customer_reserve 490008 490008 0 0.00 1 26859 103 0 0
sp_cancel_pay_from_customer 729 729 0 0.00 3 2642 125 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 1476 107 0 0
sp_cancel_supplier_invoice 5459 5451 8 0.14 3 17511 1032 0 8
sp_cancel_supplier_order 406 406 0 0.00 8 1392411 155272 0 0
sp_cancel_write_off 8302 8302 0 0.00 2 13740 188 0 0
sp_client_order 21163 21163 0 0.00 5 20396 472 0 0
sp_customer_reserve 468908 468908 0 0.00 0 47032 253 0 0
sp_fill_shopping_cart 32343 32343 0 0.00 1 20568 95 0 0
sp_fill_shopping_cart_clo_res 468863 468863 0 0.00 0 17051 20 0 0
sp_get_clo_for_invoice 5014 5014 0 0.00 0 25093 340 0 0
sp_kill_cost_storno 14410 14410 0 0.00 0 14884 71 0 0
sp_kill_qstorno_ret_qs2qd 994175 994175 0 0.00 0 20921 46 0 0
sp_kill_qty_storno 526084 526084 0 0.00 0 20950 105 0 0
sp_lock_selected_doc 21048 21048 0 0.00 0 7939 7 0 0
sp_make_cost_storno 28465 28465 0 0.00 0 19052 82 0 0
sp_multiply_rows_for_pdistr 28463 28463 0 0.00 0 12935 39 0 0
sp_multiply_rows_for_qdistr 21162 21162 0 0.00 0 19299 187 0 0
sp_pay_from_customer 7385 7385 0 0.00 6 22894 196 0 0
sp_pay_to_supplier 5937 5937 0 0.00 6 14524 196 0 0
sp_payment_common 13322 13322 0 0.00 2 22081 53 0 0
sp_qd_handle_on_cancel_clo 1834 1834 0 0.00 0 10760 332 0 0
sp_qd_handle_on_invoice_upd_sts 9954 9954 0 0.00 0 19950 525 0 0
sp_qd_handle_on_reserve_upd_sts 10129 10129 0 0.00 0 13063 61 0 0
sp_reserve_write_off 10130 10130 0 0.00 8 21016 286 0 0
sp_supplier_invoice 5638 5638 0 0.00 10 67680 3143 0 0
sp_supplier_order 5556 5556 0 0.00 7 32485 1303 0 0
srv_aggregate_perf_data 112 112 0 0.00 2291 150488 17093 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 802 102907 35419 0 0
srv_find_qd_qs_mism 993556 993556 0 0.00 0 15595 11 0 0
srv_make_invnt_saldo 533 533 0 0.00 65 62193 3548 0 0
srv_make_money_saldo 923 923 0 0.00 1 8049 314 0 0
srv_recalc_idx_stat 4 4 0 0.00 41386 313708 219935 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 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 5067 5067 0 0.00 0 10848 1008 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 11982 450 0 0
t$perf-norm:sp_cancel_client_order 1834 1834 0 0.00 0 1023 26 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 5894 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 729 729 0 0.00 0 451 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 88 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 1467 38 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 115080 6465 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 777 18 0 0
t$perf-norm:sp_client_order 21164 21164 0 0.00 0 11368 36 0 0
t$perf-norm:sp_customer_reserve 1030 1030 0 0.00 0 431 13 0 0
t$perf-norm:sp_pay_from_customer 7385 7385 0 0.00 0 10402 20 0 0
t$perf-norm:sp_pay_to_supplier 5937 5937 0 0.00 0 3179 20 0 0
t$perf-norm:sp_reserve_write_off 10130 10130 0 0.00 0 7375 23 0 0
t$perf-norm:sp_supplier_invoice 5638 5638 0 0.00 0 5510 30 0 0
t$perf-norm:sp_supplier_order 5557 5557 0 0.00 0 6271 32 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 318 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 101 6 0 0
t$perf-norm:srv_make_invnt_saldo 533 533 0 0.00 0 729 15 0 0
t$perf-norm:srv_make_money_saldo 923 923 0 0.00 0 344 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 164 80 0 0
v_add_invoice_to_stock 10282 10020 262 2.54 0 5681 28 0 262
v_all_customers 38532 38532 0 0.00 0 105 4 0 0
v_all_suppliers 30968 30968 0 0.00 0 83 5 0 0
v_all_wares 42318 42318 0 0.00 0 63 4 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 361 14 0 0
v_cancel_client_order 3668 3668 0 0.00 0 2952 12 0 0
v_cancel_customer_prepayment 1458 1458 0 0.00 0 2553 16 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 3585 14 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 1453 12 0 0
v_cancel_supplier_invoice 784 768 16 2.04 0 3381 30 0 16
v_cancel_supplier_order 812 812 0 0.00 0 631 11 0 0
v_cancel_write_off 2042 2042 0 0.00 0 2989 25 0 0
v_max_id_clo_ord 5551 5551 0 0.00 0 179 5 0 0
v_max_id_clo_res 1030 1030 0 0.00 0 3703 22 0 0
v_max_id_ord_sup 5633 5633 0 0.00 0 6948 8 0 0
v_max_non_paid_invoice 1637 1637 0 0.00 0 1461 9 0 0
v_max_non_paid_realizn 3953 3953 0 0.00 0 5570 12 0 0
v_min_id_clo_ord 5551 5551 0 0.00 0 7229 12 0 0
v_min_id_clo_res 1030 1030 0 0.00 0 6202 33 0 0
v_min_id_ord_sup 5633 5633 0 0.00 0 6846 12 0 0
v_min_non_paid_invoice 5937 5937 0 0.00 0 7567 16 0 0
v_min_non_paid_realizn 7385 7385 0 0.00 0 22074 24 0 0
v_our_firm 10650 10650 0 0.00 0 104 5 0 0
v_reserve_write_off 20258 20258 0 0.00 0 3664 12 0 0
x_lock_depdocs_on_canc_invoice 10009 10009 0 0.00 0 20919 123 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 7458 327 0 0
x_make_qstorno_1000_1200 5556 5556 0 0.00 2 32454 1109 0 0
x_make_qstorno_1200_2000 5638 5638 0 0.00 3 67471 2881 0 0
x_make_qstorno_2100_3300 468905 468905 0 0.00 0 47012 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-07-2022 07:39:48 to 08-07-2022 07:39:52.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:19 2 8192000000 8798825136 8810061824 4701680 0.057 2 1 2 0 4801104 63648 35776
05:08:20 35871 8192000000 9722630112 10020114432 794693552 8.843 100 88 176 0 825102128 8014944 22393632
05:10:01 21773 8192000000 9766374448 10072489984 839068656 9.290 100 92 184 0 875136256 14463632 21603968
05:11:26 11948 8192000000 9790777840 10110005248 860641888 9.507 100 83 166 1 902293920 22762000 18890032
05:12:43 13359 8192000000 9867390080 10199302144 911832944 10.015 100 88 174 1 976403168 45637040 18933184
05:14:00 4973 8192000000 9923274144 10269515776 956758240 10.457 100 85 163 0 1037808048 63072544 17977264
05:15:11 920 8192000000 9977719280 10329796608 989031984 10.772 100 93 182 0 1094892480 87610304 18250192
05:16:13 843 8192000000 9978853760 10338295808 1019004016 11.062 100 92 177 0 1098084048 60965040 18114992
05:17:17 1009 8192000000 10006514576 10375864320 1035057152 11.217 100 92 178 2 1129490576 76854688 17578736
05:18:20 1129 8192000000 10008544832 10379177984 1050613296 11.367 100 94 183 0 1123728464 55380800 17734368
05:19:22 1081 8192000000 10026498048 10400817152 1062482048 11.480 100 89 163 0 1142481920 64935024 15064848
05:20:24 1153 8192000000 10025589776 10408206336 1069357344 11.546 100 84 166 0 1150287888 64264912 16665632
05:21:27 6959 8192000000 10032187472 10418860032 1073756448 11.588 100 67 132 1 1149528768 62908640 12863680
05:22:36 1217 8192000000 10050975072 10436100096 1079978640 11.647 100 96 184 1 1173819952 75255616 18585696
05:23:38 987 8192000000 10042498656 10426273792 1086353136 11.708 100 89 175 0 1160202896 55398784 18450976
05:24:41 3011 8192000000 10063930880 10455887872 1085005440 11.695 100 78 146 2 1193962912 95018256 13939216
05:25:45 1637 8192000000 10047470480 10441232384 1089400848 11.737 100 80 146 2 1170320016 65921152 14998016
05:26:48 1657 8192000000 10054206016 10439925760 1081460992 11.661 100 83 157 3 1176308528 78573104 16274432
05:27:51 1218 8192000000 10050034608 10438729728 1094603872 11.786 100 78 155 0 1171754448 61643792 15506784
05:28:53 1213 8192000000 10024182560 10411446272 1079344256 11.641 100 94 176 2 1147498736 49493152 18661328
05:29:55 4367 8192000000 10023986784 10415308800 1084422672 11.690 100 77 152 0 1141874080 40869504 16581904
05:31:06 1223 8192000000 10032043568 10423185408 1066155424 11.515 100 89 178 2 1155222752 70618672 18448656
05:32:08 1672 8192000000 10013307184 10396876800 1069409488 11.546 100 82 162 1 1135333296 49610768 16313040
05:33:10 9877 8192000000 9984901408 10368372736 1036769968 11.234 98 63 123 1 1090008512 41067088 12171456
05:34:23 8664 8192000000 9992022928 10376638464 1037468784 11.240 99 56 112 0 1098491440 49694944 11327712
05:35:33 824 8192000000 9958951952 10329309184 1028894992 11.158 100 96 178 4 1080262960 32763936 18604032
05:36:34 1703 8192000000 9969270352 10347991040 1026850352 11.138 99 67 131 0 1092634800 52388288 13396160
05:37:38 1098 8192000000 10002257488 10388774912 1057721968 11.435 100 71 122 3 1131586256 61349184 12515104
05:38:44 10200 8192000000 10020026048 10414014464 1070240896 11.554 100 52 104 1 1137388624 56881952 10265776
05:40:03 11453 8192000000 10044386960 10439634944 1095784576 11.798 100 58 114 0 1163480848 55811056 11885216
05:41:19 12527 8192000000 10088333456 10493968384 1103681760 11.873 100 51 98 0 1210280048 96154016 10444272
05:42:36 3828 8192000000 10158176384 10551189504 1117723904 12.005 100 86 161 1 1274351984 139020992 17607088
05:43:44 4547 8192000000 10172710848 10580840448 1133977792 12.159 100 68 132 0 1290418784 142763136 13677856
05:44:51 13784 8192000000 10185967904 10589315072 1136082096 12.179 100 69 137 0 1301951328 151020448 14848784
05:46:21 4262 8192000000 10234343280 10643542016 1148410656 12.295 100 84 164 0 1362927328 196852960 17663712
05:47:28 5118 8192000000 10273172320 10676449280 1153648096 12.344 100 71 138 0 1382671296 213861200 15162000
05:48:38 891 8192000000 10281034368 10683555840 1165192928 12.452 100 86 164 2 1393277968 208606416 19478624
05:49:42 1874 8192000000 10302552416 10711429120 1175934192 12.552 100 93 177 1 1414769936 218884672 19951072
05:50:47 899 8192000000 10330097168 10769379328 1181193072 12.601 100 94 183 1 1437446864 236193344 20060448
05:51:50 1000 8192000000 10335262656 10740473856 1176497840 12.558 100 92 182 1 1441363808 244145904 20720064
05:52:54 939 8192000000 10363641312 10787352576 1180754928 12.597 100 95 187 0 1476698160 274364112 21579120
05:54:03 1178 8192000000 10394338864 10798678016 1183520720 12.623 100 96 184 1 1504418896 300588784 20309392
05:55:07 1050 8192000000 10423357312 10831048704 1180452128 12.594 100 90 170 0 1522808672 323137568 19218976
05:56:11 792 8192000000 10409106512 10847604736 1182765072 12.616 100 97 190 0 1515704976 311342480 21597424
05:57:14 887 8192000000 10421383616 10846453760 1178657168 12.578 100 88 166 1 1529784992 332490688 18637136
05:58:18 3447 8192000000 10455149936 10874331136 1185214416 12.639 100 85 162 4 1558709952 354330976 19164560
05:59:24 1445 8192000000 10476150128 10896732160 1184168992 12.629 100 93 183 2 1579036336 373647232 21220112
06:00:27 7768 8192000000 10417697568 10898178048 1171098672 12.507 100 46 88 0 1519681232 336322304 12260256
06:01:39 7564 8192000000 10412540048 10871525376 1153209440 12.340 99 59 116 0 1512392128 344533808 14648880
06:02:52 8068 8192000000 10416067472 10862682112 1167193136 12.471 100 45 86 0 1522148624 343330832 11624656
06:04:01 16159 8192000000 10366322720 10842828800 1156690608 12.372 99 36 72 0 1455302512 289658320 8953584
06:05:47 14916 8192000000 10254104832 10684555264 1125878800 12.082 100 26 51 0 1360032112 227717888 6435424
06:07:04 57217 8192000000 10174122352 10607046656 1062904640 11.484 94 20 38 1 1209204336 142431696 3868000
06:09:02 11544 8192000000 10177284512 10614353920 1101950624 11.856 98 55 103 1 1258993280 145416240 11626416
06:10:16 1236 8192000000 10186949808 10650054656 1135317712 12.171 100 83 161 2 1286334720 133166464 17850544
06:11:18 2292 8192000000 10206934768 10674663424 1153089536 12.338 100 76 150 1 1308466688 138475344 16901808
06:12:21 52254 8192000000 10194964928 10614059008 1150908272 12.318 100 60 114 1 1288667600 124722640 13036688
06:14:18 46233 8192000000 10175562336 10618126336 1141813136 12.233 99 42 77 0 1279662352 129973552 7875664
06:16:13 38558 8192000000 10214319920 10664398848 1146325728 12.275 100 22 44 0 1303846400 152311232 5209440
06:19:03 81955 8192000000 10140794848 10630758400 1089928016 11.742 96 9 18 0 1179673264 87736208 2009040
06:21:28 88824 8192000000 10117979856 10560438272 1119602864 12.023 99 27 53 1 1188729776 62515600 6611312
06:23:57 79126 8192000000 10072664592 10530353152 1110470480 11.937 99 25 50 0 1180618144 64787472 5360192
06:26:25 67606 8192000000 10045560336 10514915328 1112039760 11.952 99 35 65 0 1160881984 41123664 7718560
06:28:36 50336 8192000000 10078798864 10512093184 1116142272 11.991 99 22 42 0 1167792144 46892016 4757856
06:30:29 27579 8192000000 10084663824 10511712256 1128330912 12.106 100 73 132 1 1189139920 45697584 15111424
06:31:57 91744 8192000000 10086737440 10562080768 1111709680 11.949 98 13 26 0 1163641664 49077600 2854384
06:34:30 80385 8192000000 10031229376 10510024704 1083976736 11.685 96 13 25 0 1112026976 25637792 2412448
06:36:50 75194 8192000000 10053452528 10522972160 1128329472 12.106 100 25 46 1 1171311984 37637520 5344992
06:39:12 47089 8192000000 10062637504 10556588032 1140188464 12.217 100 12 24 0 1183880928 41507696 2184768
06:41:10 42465 8192000000 10063655216 10528714752 1139630688 12.212 100 21 41 1 1164975648 21490544 3854416
06:42:54 24958 8192000000 10140240240 10584260608 1155564736 12.362 100 48 96 1 1237272688 70917680 10790272
06:44:23 36337 8192000000 10180840976 10635636736 1145827632 12.270 100 26 52 0 1278546560 126748672 5970256
06:46:11 32193 8192000000 10226347520 10677317632 1156374288 12.369 100 25 50 0 1316021232 153371120 6275824
06:47:52 32907 8192000000 10251815264 10697494528 1164821040 12.448 100 33 64 0 1351213616 178378096 8014480
06:49:37 55921 8192000000 10143160816 10648883200 1145955680 12.272 99 13 25 0 1258783472 110128080 2699712
06:51:34 18005 8192000000 10103635616 10536284160 1141474288 12.229 99 73 136 2 1185828480 30758160 13596032
06:52:55 24791 8192000000 10163180672 10627334144 1145608368 12.268 99 62 123 0 1259638576 101705632 12324576
06:54:27 102906 8192000000 10078501568 10533236736 1101786032 11.855 95 37 72 1 1156970336 47844128 7340176
06:57:20 19353 8192000000 10097411072 10529030144 1141209440 12.227 100 58 115 0 1204107280 51714400 11183440
06:58:41 30553 8192000000 10105098912 10601635840 1129043056 12.112 99 24 46 1 1209451248 75661840 4746352
07:00:12 56228 8192000000 10045571536 10512887808 1071508112 11.566 95 5 10 0 1117479472 45034272 937088
07:02:08 56873 8192000000 10048255472 10522112000 1110487296 11.937 99 16 32 1 1167151376 52700320 3963760
07:04:06 87219 8192000000 10047995776 10522484736 1085442768 11.699 96 9 16 0 1125890624 38906784 1541072
07:06:33 65775 8192000000 10097486624 10553901056 1101805024 11.855 98 35 68 0 1171287664 62687504 6795136
07:08:40 21385 8192000000 10108711968 10532151296 1115454112 11.984 99 50 100 0 1194676000 69350912 9870976
07:10:04 76017 8192000000 10056355808 10493906944 1108561296 11.919 99 23 43 0 1162096048 49344688 4190064
07:12:21 40698 8192000000 10077394400 10518593536 1114059840 11.971 99 40 80 0 1175420464 53464032 7896592
07:14:07 37101 8192000000 10104643536 10556059648 1125698368 12.081 100 43 86 0 1201798800 67539280 8561152
07:15:45 88246 8192000000 10106921456 10570219520 1112504848 11.956 98 33 66 1 1168477808 49516304 6456656
07:18:42 27769 8192000000 10084843424 10525827072 1133679680 12.156 100 53 95 0 1187836368 44029056 10127632
07:21:12 79272 8192000000 10066869008 10586562560 1132115680 12.141 100 4 8 0 1183966928 51008240 843008
07:23:39 82363 8192000000 10083728800 10551492608 1131958336 12.140 99 19 33 1 1175843664 40155424 3729904
07:26:02 51396 8192000000 10120969824 10581721088 1122029104 12.046 98 29 59 0 1206589920 78258384 6302432
07:28:04 44283 8192000000 10141536384 10601218048 1147949360 12.290 100 28 56 0 1249842896 95550800 6342736
07:29:55 69857 8192000000 10079503440 10578677760 1138530128 12.202 100 21 39 1 1204522560 62228192 3764240
07:32:09 16208 8192000000 10077327824 10502586368 1118405216 12.012 99 48 93 0 1155889824 27655184 9829424
07:33:25 61933 8192000000 10100843936 10612154368 1126039024 12.084 99 40 80 0 1246143696 111798704 8305968
07:35:35 96614 8192000000 10079011616 10542657536 1107913776 11.913 97 22 42 1 1169190800 57121024 4156000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-07-2022 07:39:54 to 08-07-2022 07:40:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 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 08-07-2022 07:40:11 to 08-07-2022 07:40:13.

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          226253
MON$OLDEST_ACTIVE               230068
MON$OLDEST_SNAPSHOT             230068
MON$NEXT_TRANSACTION            230068
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                       2079968
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.2.2796 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2796 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2796 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 ---
:97: 
:98: mscs-test-161.budzdorov.ru    Fri Jul  8 07:38:10 2022
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:100: 
--- end of diff output ---

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

08.07.22 07:40:14. 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
2022-07-08 07:40:17 No severe PSQL-related problems occured
08.07.22 07:40:18. End of report.