Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.01.2023 04: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 14635936 kB
MemAvailable 30416056 kB
Buffers 20 kB
Cached 17027888 kB
SwapCached 1644 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-01-22 04:37:25
DTS_END                         2023-01-22 04:37:25.6400
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33708
TRN_ID                          226886
ATT_ID                          667
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: 9153.71 24475 1098445
customer order: creation 181.36 457 21763
customer order: refuse 16.00 474 1920
order to supplier: creation 47.27 1304 5672
order to supplier: removal 3.28 186944 393
invoice (draft): creation 47.06 2994 5647
invoice (draft): removal 48.28 1054 5794
invoice accept: apply 44.18 28330 5302
invoice accept: cancel 44.03 1419 5283
customer reserve: creation 4091.23 269 490947
customer reserve: removal 4330.90 112 519708
realization accept: apply 89.20 308 10704
realization accept: cancel 74.79 198 8975
payment from customer: creation 62.82 199 7538
payment from customer: removal 5.81 146 697
payment to supplier: creation 50.59 219 6071
payment to supplier: removal 3.43 160 412
service: total inventory turnovers 4.06 4490 487
service: total monetary turnovers 8.52 359 1022
service: aggregate perf. data 0.88 17774 106
service: refresh index statistics 0.03 242288 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-01-2023 04:39:18 to 22-01-2023 04:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7389
TEST_TIME 2 5744
TEST_TIME 3 6534
TEST_TIME 4 5862
TEST_TIME 5 5974
TEST_TIME 6 5985
TEST_TIME 7 6056
TEST_TIME 8 6028
TEST_TIME 9 6188
TEST_TIME 10 6418
TEST_TIME 11 6368
TEST_TIME 12 6538
TEST_TIME 13 6842
TEST_TIME 14 6933
TEST_TIME 15 7215
TEST_TIME 16 7328
TEST_TIME 17 7384
TEST_TIME 18 7609
TEST_TIME 19 7696
TEST_TIME 20 7859
TEST_TIME 21 7926
TEST_TIME 22 8024
TEST_TIME 23 8091
TEST_TIME 24 8230
TEST_TIME 25 8265
TEST_TIME 26 8600
TEST_TIME 27 8677
TEST_TIME 28 9043
TEST_TIME 29 9191
TEST_TIME 30 9405
TEST_TIME 31 9595
TEST_TIME 32 9751
TEST_TIME 33 9760
TEST_TIME 34 9761
TEST_TIME 35 10124
TEST_TIME 36 10211
TEST_TIME 37 10448
TEST_TIME 38 10398
TEST_TIME 39 10499
TEST_TIME 40 10460
TEST_TIME 41 10773
TEST_TIME 42 10876
TEST_TIME 43 10751
TEST_TIME 44 10752
TEST_TIME 45 10530
TEST_TIME 46 10511
TEST_TIME 47 10534
TEST_TIME 48 10385
TEST_TIME 49 10334
TEST_TIME 50 10216
TEST_TIME 51 10075
TEST_TIME 52 10024
TEST_TIME 53 9973
TEST_TIME 54 9911
TEST_TIME 55 9868
TEST_TIME 56 9932
TEST_TIME 57 9795
TEST_TIME 58 9763
TEST_TIME 59 9839
TEST_TIME 60 9774
TEST_TIME 61 9672
TEST_TIME 62 9537
TEST_TIME 63 9650
TEST_TIME 64 9659
TEST_TIME 65 9594
TEST_TIME 66 9556
TEST_TIME 67 9514
TEST_TIME 68 9481
TEST_TIME 69 9503
TEST_TIME 70 9470
TEST_TIME 71 9480
TEST_TIME 72 9555
TEST_TIME 73 9615
TEST_TIME 74 9588
TEST_TIME 75 9609
TEST_TIME 76 9627
TEST_TIME 77 9633
TEST_TIME 78 9585
TEST_TIME 79 9512
TEST_TIME 80 9434
TEST_TIME 81 9356
TEST_TIME 82 9358
TEST_TIME 83 9408
TEST_TIME 84 9364
TEST_TIME 85 9350
TEST_TIME 86 9293
TEST_TIME 87 9208
TEST_TIME 88 9150
TEST_TIME 89 9106
TEST_TIME 90 9092
TEST_TIME 91 9072
TEST_TIME 92 9049
TEST_TIME 93 9032
TEST_TIME 94 9072
TEST_TIME 95 9029
TEST_TIME 96 9026
TEST_TIME 97 9058
TEST_TIME 98 9077
TEST_TIME 99 9037
TEST_TIME 100 9059
TEST_TIME 101 9130
TEST_TIME 102 9117
TEST_TIME 103 9161
TEST_TIME 104 9239
TEST_TIME 105 9230
TEST_TIME 106 9400
TEST_TIME 107 9346
TEST_TIME 108 9308
TEST_TIME 109 9369
TEST_TIME 110 9410
TEST_TIME 111 9366
TEST_TIME 112 9376
TEST_TIME 113 9375
TEST_TIME 114 9382
TEST_TIME 115 9407
TEST_TIME 116 9344
TEST_TIME 117 9324
TEST_TIME 118 9275
TEST_TIME 119 9234
TEST_TIME 120 9181

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-01-2023 04:39:21 to 22-01-2023 04:39:25.

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 1548566 1548566 0 0.00 0 23889 15 0 0
doc_list_biud 1548558 1548558 0 0.00 0 27257 44 0 0
sp_add_invoice_to_stock 5442 5302 140 2.57 45 186508 28331 0 140
sp_cancel_adding_invoice 5283 5283 0 0.00 6 64502 1420 0 0
sp_cancel_client_order 1920 1920 0 0.00 5 10653 475 0 0
sp_cancel_customer_reserve 519708 519708 0 0.00 1 26798 113 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 4 6636 147 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 4 8529 160 0 0
sp_cancel_supplier_invoice 5805 5794 11 0.18 2 27262 1054 0 11
sp_cancel_supplier_order 393 393 0 0.00 8 1467659 186944 0 0
sp_cancel_write_off 8975 8975 0 0.00 2 21050 198 0 0
sp_client_order 21763 21763 0 0.00 5 62490 458 0 0
sp_customer_reserve 490947 490947 0 0.00 1 96990 270 0 0
sp_fill_shopping_cart 33075 33075 0 0.00 1 14925 89 0 0
sp_fill_shopping_cart_clo_res 490913 490913 0 0.00 0 14145 19 0 0
sp_get_clo_for_invoice 5263 5263 0 0.00 0 36677 267 0 0
sp_kill_cost_storno 15367 15367 0 0.00 0 7062 84 0 0
sp_kill_qstorno_ret_qs2qd 1054578 1054578 0 0.00 0 27255 48 0 0
sp_kill_qty_storno 558039 558039 0 0.00 0 27256 113 0 0
sp_lock_selected_doc 22086 22086 0 0.00 0 8271 7 0 0
sp_make_cost_storno 29576 29576 0 0.00 0 17243 87 0 0
sp_multiply_rows_for_pdistr 29575 29575 0 0.00 0 9131 40 0 0
sp_multiply_rows_for_qdistr 21762 21762 0 0.00 0 11002 152 0 0
sp_pay_from_customer 7538 7538 0 0.00 5 33127 199 0 0
sp_pay_to_supplier 6071 6071 0 0.00 6 18315 220 0 0
sp_payment_common 13609 13609 0 0.00 2 12647 54 0 0
sp_qd_handle_on_cancel_clo 1920 1920 0 0.00 0 10645 393 0 0
sp_qd_handle_on_invoice_upd_sts 10546 10546 0 0.00 0 25169 612 0 0
sp_qd_handle_on_reserve_upd_sts 10703 10703 0 0.00 0 14817 70 0 0
sp_reserve_write_off 10704 10704 0 0.00 8 23894 308 0 0
sp_supplier_invoice 5647 5647 0 0.00 8 72970 2994 0 0
sp_supplier_order 5672 5672 0 0.00 6 25583 1305 0 0
srv_aggregate_perf_data 106 106 0 0.00 2665 198449 17774 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 668 149716 31187 0 0
srv_find_qd_qs_mism 1045952 1045952 0 0.00 0 23884 12 0 0
srv_make_invnt_saldo 487 487 0 0.00 39 109200 4490 0 0
srv_make_money_saldo 1022 1022 0 0.00 1 9501 360 0 0
srv_recalc_idx_stat 4 4 0 0.00 64782 376493 242289 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5312 5312 0 0.00 0 31285 1096 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 2 5877 502 0 0
t$perf-norm:sp_cancel_client_order 1920 1920 0 0.00 0 4861 34 0 0
t$perf-norm:sp_cancel_customer_reserve 750 750 0 0.00 0 1695 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 538 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 1899 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 2919 36 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 130235 7928 0 0
t$perf-norm:sp_cancel_write_off 1065 1065 0 0.00 0 2628 25 0 0
t$perf-norm:sp_client_order 21764 21764 0 0.00 0 6127 40 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 29433 43 0 0
t$perf-norm:sp_pay_from_customer 7538 7538 0 0.00 0 9809 20 0 0
t$perf-norm:sp_pay_to_supplier 6071 6071 0 0.00 0 2681 19 0 0
t$perf-norm:sp_reserve_write_off 10704 10704 0 0.00 0 3471 23 0 0
t$perf-norm:sp_supplier_invoice 5647 5647 0 0.00 0 6847 34 0 0
t$perf-norm:sp_supplier_order 5672 5672 0 0.00 0 5361 33 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 493 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 131 6 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 16230 55 0 0
t$perf-norm:srv_make_money_saldo 1022 1022 0 0.00 0 16383 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 180 109 0 0
v_add_invoice_to_stock 10800 10520 280 2.59 0 16900 29 0 280
v_all_customers 39792 39792 0 0.00 0 72 4 0 0
v_all_suppliers 31688 31688 0 0.00 0 80 5 0 0
v_all_wares 43518 43518 0 0.00 0 88 5 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 1414 18 0 0
v_cancel_client_order 3840 3840 0 0.00 0 4319 13 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 1575 14 0 0
v_cancel_customer_reserve 1500 1500 0 0.00 0 995 14 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 1572 13 0 0
v_cancel_supplier_invoice 906 884 22 2.42 0 2069 25 0 22
v_cancel_supplier_order 786 786 0 0.00 0 1547 15 0 0
v_cancel_write_off 2130 2130 0 0.00 0 1465 27 0 0
v_max_id_clo_ord 5671 5671 0 0.00 0 447 6 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 1561 14 0 0
v_max_id_ord_sup 5645 5645 0 0.00 0 3604 8 0 0
v_max_non_paid_invoice 1543 1543 0 0.00 0 5252 13 0 0
v_max_non_paid_realizn 3946 3946 0 0.00 0 1009 9 0 0
v_min_id_clo_ord 5671 5671 0 0.00 0 3441 9 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 1203 11 0 0
v_min_id_ord_sup 5645 5645 0 0.00 0 5966 16 0 0
v_min_non_paid_invoice 6071 6071 0 0.00 0 8569 20 0 0
v_min_non_paid_realizn 7538 7538 0 0.00 0 12641 20 0 0
v_our_firm 10910 10910 0 0.00 0 289 5 0 0
v_reserve_write_off 21406 21406 0 0.00 0 16282 15 0 0
x_lock_depdocs_on_canc_invoice 10635 10635 0 0.00 0 29299 127 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 4857 394 0 0
x_make_qstorno_1000_1200 5672 5672 0 0.00 2 25450 1115 0 0
x_make_qstorno_1200_2000 5647 5647 0 0.00 4 70775 2763 0 0
x_make_qstorno_2100_3300 490944 490944 0 0.00 0 96734 236 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-01-2023 04:39:26 to 22-01-2023 04:39:30.

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
02:07:29 2 8192000000 8798590016 8809861120 4610720 0.056 2 1 2 0 4710144 63648 35776
02:08:30 37113 8192000000 9736053936 10037993472 803234544 8.929 100 78 156 0 829714352 7702032 18777776
02:10:14 18616 8192000000 9777053024 10088001536 849348800 9.394 100 91 182 0 883226816 12685648 21192368
02:11:39 14315 8192000000 9802459488 10122493952 871468080 9.615 100 83 166 0 909586960 19548496 18570384
02:12:57 11148 8192000000 9861269024 10192044032 905179312 9.950 100 84 166 0 970761296 48021920 17560064
02:14:09 4707 8192000000 9907870464 10248736768 937972704 10.273 100 86 171 0 1021122272 64641104 18508464
02:15:24 3199 8192000000 9932513792 10285051904 971115232 10.598 100 80 153 1 1046622432 59657040 15850160
02:16:30 1962 8192000000 9987740976 10346364928 1009351328 10.969 100 82 160 0 1103283840 77320944 16611568
02:17:34 1509 8192000000 9975981936 10343268352 1027896944 11.148 100 79 152 0 1096925904 54085648 14943312
02:18:38 1376 8192000000 10014702864 10389692416 1052657968 11.386 100 92 175 2 1136777728 66190256 17929504
02:19:41 1042 8192000000 10030922272 10414751744 1064556400 11.500 100 92 176 2 1156931136 73680832 18693904
02:20:44 1061 8192000000 10053501664 10433474560 1066284864 11.517 100 89 171 2 1175158128 90684240 18189024
02:21:47 969 8192000000 10055780656 10438635520 1073771216 11.588 100 94 180 2 1176166240 83044080 19350944
02:22:50 836 8192000000 10054275840 10439008256 1080560368 11.653 100 85 162 1 1172907648 75268016 17079264
02:23:53 1602 8192000000 10066667184 10460258304 1080895792 11.656 100 99 184 1 1188026800 88153920 18977088
02:24:55 1165 8192000000 10031464944 10419793920 1088676768 11.730 100 90 169 1 1155069120 48829904 17562448
02:25:58 1167 8192000000 10066677216 10456080384 1092945968 11.771 100 94 181 1 1189550384 78321968 18282448
02:27:00 1184 8192000000 10059743024 10446966784 1102355168 11.860 100 96 191 2 1185009408 63722480 18931760
02:28:03 1103 8192000000 10075852480 10466549760 1103521664 11.871 100 99 185 3 1196742880 74438080 18783136
02:29:04 1015 8192000000 10048405840 10445344768 1094986592 11.790 100 76 142 2 1170068544 60861760 14220192
02:30:07 965 8192000000 10025184384 10409021440 1070594912 11.558 99 89 167 3 1145289440 56341168 18353360
02:31:09 970 8192000000 10019166480 10402787328 1080325744 11.651 100 91 176 0 1140154240 41491696 18336800
02:32:11 3020 8192000000 10021798128 10408419328 1071754592 11.569 100 88 167 5 1140308096 52056880 16496624
02:33:16 1166 8192000000 9996502224 10374787072 1062990592 11.485 100 82 153 2 1126660688 46808960 16861136
02:34:18 2854 8192000000 9999731344 10380972032 1059427904 11.451 100 69 127 1 1120574000 48294320 12851776
02:35:22 2247 8192000000 10014585280 10400419840 1056926768 11.427 100 80 144 2 1142714400 70880064 14907568
02:36:26 1239 8192000000 10002611120 10379419648 1038184304 11.247 99 93 177 0 1126582048 70369360 18028384
02:37:28 2097 8192000000 9988472624 10366066688 1043424848 11.298 100 80 146 3 1107840304 49256992 15158464
02:38:44 2836 8192000000 10022162128 10403020800 1058964064 11.447 100 77 149 0 1137297200 62403264 15929872
02:39:52 3242 8192000000 10034005600 10419585024 1063449776 11.489 100 68 127 0 1156640768 80125328 13065664
02:41:00 937 8192000000 10079520560 10458681344 1093525328 11.776 100 93 173 1 1188082400 76590976 17966096
02:42:05 5279 8192000000 10101214208 10498789376 1099360240 11.832 100 71 142 0 1225458768 110950320 15148208
02:43:19 12234 8192000000 10130263920 10530381824 1117532208 12.004 100 66 130 1 1257529968 126217856 13779904
02:44:37 6472 8192000000 10139055904 10542968832 1123609248 12.061 100 73 143 0 1259283696 119329280 16345168
02:45:51 1448 8192000000 10202637520 10597695488 1132279600 12.143 100 91 172 2 1317034640 165716784 19038256
02:46:58 1221 8192000000 10239305472 10637774848 1147775376 12.289 100 93 179 2 1349832208 181889632 20167200
02:48:04 2916 8192000000 10256643856 10661662720 1155857952 12.364 100 81 157 1 1370550608 196425728 18266928
02:49:14 2652 8192000000 10301946752 10709983232 1162884272 12.430 100 90 177 0 1414241072 231685952 19670848
02:50:19 786 8192000000 10296277488 10717892608 1166670640 12.466 100 96 185 1 1411945840 223327888 21947312
02:51:23 872 8192000000 10318280912 10724495360 1170747328 12.504 100 92 179 0 1431993600 239084960 22161312
02:52:28 1393 8192000000 10355248080 10781839360 1176566960 12.558 100 97 181 2 1462711264 265269248 20875056
02:53:34 1330 8192000000 10374253120 10791022592 1186364608 12.650 100 96 179 2 1484939856 277930528 20644720
02:54:38 1491 8192000000 10365666400 10794196992 1187632384 12.661 100 94 180 1 1474861968 267153952 20075632
02:55:44 1029 8192000000 10387406784 10801459200 1182190864 12.611 100 95 186 0 1496556448 292947728 21417856
02:56:48 1020 8192000000 10405179600 10826022912 1177952080 12.571 100 96 179 0 1512578144 314394592 20231472
02:57:53 1401 8192000000 10392263216 10818920448 1167178592 12.470 100 94 184 0 1497697472 307662192 22856688
02:58:58 828 8192000000 10407335184 10822713344 1167427024 12.473 99 92 174 0 1514423488 327098992 19897472
03:00:02 1117 8192000000 10437077824 10856681472 1170807568 12.504 100 98 190 1 1540863600 348079584 21976448
03:01:07 927 8192000000 10472145152 10903449600 1181878336 12.608 100 99 196 3 1578810640 372783616 24148688
03:02:11 1915 8192000000 10460624848 10903818240 1171755632 12.513 100 95 184 1 1557830112 364499408 21575072
03:03:15 798 8192000000 10460054144 10910752768 1175649168 12.550 100 96 184 2 1568504608 370814416 22041024
03:04:18 1057 8192000000 10484743536 10902564864 1168022160 12.478 100 77 152 0 1590165408 403871568 18271680
03:05:23 4083 8192000000 10429714448 10870743040 1160834192 12.411 99 55 102 0 1551834496 377272144 13728160
03:06:33 27038 8192000000 10391873136 10891960320 1149010992 12.300 99 36 71 0 1512549552 354404816 9133744
03:08:21 31723 8192000000 10348113616 10813906944 1151952736 12.328 100 30 60 0 1448742560 289170304 7619520
03:10:37 46600 8192000000 10322971744 10776199168 1108818192 11.921 97 31 59 0 1410248160 293975792 7454176
03:12:26 7917 8192000000 10207543120 10702802944 1123791440 12.063 98 40 77 1 1293404592 160910928 8702224
03:13:38 659 8192000000 10274270864 10693365760 1155709408 12.363 100 97 193 1 1371599504 195071760 20818336
03:14:40 2308 8192000000 10230609168 10693947392 1162746896 12.429 100 54 107 2 1334440864 158612144 13081824
03:16:04 28906 8192000000 10216673760 10698493952 1157233488 12.377 100 33 64 0 1346766016 181926848 7605680
03:17:51 13180 8192000000 10216935440 10691571712 1133778736 12.157 99 19 36 1 1298560064 160320480 4460848
03:19:05 34984 8192000000 10141862560 10637496320 1139610208 12.212 99 14 28 0 1226219296 83965104 2643984
03:20:58 94184 8192000000 10115499920 10569109504 1137244336 12.190 99 24 46 0 1212375984 69839264 5292384
03:24:05 89628 8192000000 10085872528 10518908928 1127926112 12.102 98 27 50 0 1149753328 16690432 5136784
03:26:36 116391 8192000000 10077105968 10533867520 1119886976 12.026 98 44 86 1 1177086192 47506320 9692896
03:29:34 1045 8192000000 10138046096 10561597440 1148103440 12.292 100 83 159 1 1229713632 63449280 18160912
03:30:40 1241 8192000000 10132684032 10563874816 1150115520 12.311 100 78 142 1 1230686816 65725616 14845680
03:31:42 19829 8192000000 10177163104 10609057792 1137362080 12.191 99 60 113 0 1236511120 86747392 12401648
03:33:04 89187 8192000000 10170861696 10675298304 1146341152 12.275 100 20 40 0 1269741440 118447168 4953120
03:35:35 110794 8192000000 10165650640 10638069760 1135024192 12.169 98 27 54 0 1301547040 160263744 6259104
03:38:29 72344 8192000000 10153464048 10613919744 1112812256 11.959 97 16 30 0 1230033216 114186320 3034640
03:40:52 86715 8192000000 10057558880 10541449216 1081206736 11.659 95 18 34 0 1149053520 64346752 3500032
03:43:28 9951 8192000000 10058917712 10492923904 1105097760 11.886 99 68 136 1 1161460768 41623840 14739168
03:44:39 4904 8192000000 10086868464 10514948096 1125343072 12.077 100 66 128 0 1185965728 47849248 12773408
03:45:45 10184 8192000000 10122901312 10553384960 1126814624 12.091 99 56 111 0 1213944992 75896864 11233504
03:47:09 30340 8192000000 10114289504 10553880576 1121968368 12.046 99 30 59 0 1205138176 77286144 5883664
03:48:39 51164 8192000000 10114802464 10574430208 1122584112 12.051 99 17 34 0 1237237872 110737248 3916512
03:50:32 87258 8192000000 10092476336 10539220992 1101569456 11.853 97 31 62 0 1146960400 39366992 6023952
03:53:02 45061 8192000000 10146792816 10641981440 1132519472 12.145 99 24 48 0 1224065584 86753120 4792992
03:54:47 111879 8192000000 10060117664 10529202176 1120299744 12.030 99 11 22 0 1184026992 61429152 2298096
03:57:41 43934 8192000000 10095215312 10541555712 1136113744 12.179 100 30 56 0 1193990576 50996848 6879984
03:59:26 83230 8192000000 10063632736 10580144128 1137456384 12.192 100 9 18 0 1183314224 44077536 1780304
04:02:01 43688 8192000000 10055934192 10515292160 1101903392 11.856 98 24 47 0 1140395536 33411776 5080368
04:03:48 50585 8192000000 10021182592 10490368000 1077406096 11.623 96 2 4 0 1104271552 26578128 287328
04:05:39 46082 8192000000 10022609856 10484723712 1084199360 11.687 97 13 26 0 1115672016 28960496 2512160
04:07:27 50698 8192000000 10066328400 10525134848 1115219168 11.982 99 18 36 0 1152907120 34781376 2906576
04:09:22 26532 8192000000 10127632032 10570665984 1142673456 12.241 100 34 68 0 1221306880 71228752 7404672
04:10:49 15535 8192000000 10184994960 10629562368 1140575168 12.221 99 42 82 0 1277679248 128041632 9062448
04:12:05 13707 8192000000 10167427984 10603188224 1112889776 11.960 98 48 94 0 1249195584 126330384 9975424
04:13:19 26591 8192000000 10199974480 10636996608 1116366864 11.993 99 44 88 0 1279186880 152631776 10188240
04:14:46 11503 8192000000 10204637040 10656907264 1135869408 12.177 100 41 78 1 1308501712 163282112 9350192
04:16:00 21252 8192000000 10212984096 10647867392 1130696416 12.128 99 38 74 0 1289904720 150953904 8254400
04:17:26 14636 8192000000 10261436160 10716295168 1149589888 12.306 100 42 84 0 1340647680 181683472 9374320
04:18:49 26819 8192000000 10247410544 10696536064 1137694672 12.194 99 34 66 0 1324156368 179658832 6802864
04:20:18 74121 8192000000 10220024480 10687717376 1131337136 12.134 99 23 45 0 1359253456 222400096 5516224
04:23:23 71277 8192000000 10085320448 10521473024 1135752848 12.176 100 14 26 0 1211391344 72791392 2847104
04:25:36 30301 8192000000 10183510880 10616721408 1155420384 12.360 100 45 88 2 1267953920 102887632 9645904
04:27:08 63782 8192000000 10064926704 10503692288 1098025600 11.819 96 20 38 1 1127958736 25424240 4508896
04:29:19 20132 8192000000 10110767952 10542088192 1110639328 11.938 99 49 98 0 1196981488 76769296 9572864
04:30:43 149716 8192000000 10071449440 10522013696 1007183168 10.948 90 13 26 0 1033028080 22814176 3030736
04:34:13 136585 8192000000 10092610512 10543190016 1108431968 11.918 99 34 66 0 1181647696 66623008 6592720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 22-01-2023 04:39:32 to 22-01-2023 04:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-01-2023 04:39:50 to 22-01-2023 04:39:52.

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          225020
MON$OLDEST_ACTIVE               227005
MON$OLDEST_SNAPSHOT             227005
MON$NEXT_TRANSACTION            227005
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                       2110016
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 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 ---
--- end of diff output ---

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

22.01.23 04:39:53. 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-01-22 04:39:55 No severe PSQL-related problems occured
22.01.23 04:39:57. End of report.