Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.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 15151068 kB
MemAvailable 30383064 kB
Buffers 20 kB
Cached 16477576 kB
SwapCached 2688 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-19 04:37:24
DTS_END                         2023-01-19 04:37:24.5480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43360
TRN_ID                          208639
ATT_ID                          600
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: 8633.63 23214 1036035
customer order: creation 159.72 525 19166
customer order: refuse 13.66 465 1639
order to supplier: creation 41.74 1399 5009
order to supplier: removal 3.33 187069 399
invoice (draft): creation 42.33 3392 5080
invoice (draft): removal 48.32 1113 5798
invoice accept: apply 39.08 28116 4689
invoice accept: cancel 43.73 1346 5248
customer reserve: creation 3654.57 267 438548
customer reserve: removal 4316.60 113 517992
realization accept: apply 75.79 318 9095
realization accept: cancel 73.69 187 8843
payment from customer: creation 56.39 199 6767
payment from customer: removal 5.35 140 642
payment to supplier: creation 44.65 205 5358
payment to supplier: removal 2.94 144 353
service: total inventory turnovers 3.90 4225 468
service: total monetary turnovers 6.88 371 825
service: aggregate perf. data 0.93 14817 111
service: refresh index statistics 0.04 219877 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-01-2023 04:38:28 to 19-01-2023 04:38:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6669
TEST_TIME 2 5937
TEST_TIME 3 5876
TEST_TIME 4 5605
TEST_TIME 5 5435
TEST_TIME 6 5442
TEST_TIME 7 5337
TEST_TIME 8 5185
TEST_TIME 9 4976
TEST_TIME 10 5314
TEST_TIME 11 5262
TEST_TIME 12 5361
TEST_TIME 13 5510
TEST_TIME 14 5648
TEST_TIME 15 6054
TEST_TIME 16 6149
TEST_TIME 17 6404
TEST_TIME 18 6801
TEST_TIME 19 7074
TEST_TIME 20 7261
TEST_TIME 21 7486
TEST_TIME 22 7712
TEST_TIME 23 7637
TEST_TIME 24 7595
TEST_TIME 25 8141
TEST_TIME 26 8305
TEST_TIME 27 8537
TEST_TIME 28 9137
TEST_TIME 29 9465
TEST_TIME 30 9658
TEST_TIME 31 9817
TEST_TIME 32 10330
TEST_TIME 33 10474
TEST_TIME 34 10440
TEST_TIME 35 10338
TEST_TIME 36 10216
TEST_TIME 37 10251
TEST_TIME 38 10151
TEST_TIME 39 10041
TEST_TIME 40 10028
TEST_TIME 41 10042
TEST_TIME 42 9942
TEST_TIME 43 9795
TEST_TIME 44 9672
TEST_TIME 45 9767
TEST_TIME 46 9763
TEST_TIME 47 9715
TEST_TIME 48 9559
TEST_TIME 49 9431
TEST_TIME 50 9459
TEST_TIME 51 9448
TEST_TIME 52 9347
TEST_TIME 53 9218
TEST_TIME 54 9123
TEST_TIME 55 9060
TEST_TIME 56 9035
TEST_TIME 57 9081
TEST_TIME 58 9017
TEST_TIME 59 8917
TEST_TIME 60 8865
TEST_TIME 61 8817
TEST_TIME 62 8881
TEST_TIME 63 8856
TEST_TIME 64 9172
TEST_TIME 65 9116
TEST_TIME 66 9067
TEST_TIME 67 9029
TEST_TIME 68 9039
TEST_TIME 69 8987
TEST_TIME 70 9024
TEST_TIME 71 9085
TEST_TIME 72 9055
TEST_TIME 73 9015
TEST_TIME 74 9061
TEST_TIME 75 9064
TEST_TIME 76 9121
TEST_TIME 77 9099
TEST_TIME 78 9181
TEST_TIME 79 9076
TEST_TIME 80 9049
TEST_TIME 81 9034
TEST_TIME 82 8967
TEST_TIME 83 8977
TEST_TIME 84 8959
TEST_TIME 85 8950
TEST_TIME 86 8963
TEST_TIME 87 8906
TEST_TIME 88 8883
TEST_TIME 89 8825
TEST_TIME 90 8788
TEST_TIME 91 8811
TEST_TIME 92 8764
TEST_TIME 93 8744
TEST_TIME 94 8754
TEST_TIME 95 8714
TEST_TIME 96 8713
TEST_TIME 97 8728
TEST_TIME 98 8771
TEST_TIME 99 8765
TEST_TIME 100 8782
TEST_TIME 101 8809
TEST_TIME 102 8862
TEST_TIME 103 8932
TEST_TIME 104 8883
TEST_TIME 105 8953
TEST_TIME 106 8962
TEST_TIME 107 8920
TEST_TIME 108 8843
TEST_TIME 109 8802
TEST_TIME 110 8787
TEST_TIME 111 8750
TEST_TIME 112 8723
TEST_TIME 113 8693
TEST_TIME 114 8695
TEST_TIME 115 8701
TEST_TIME 116 8707
TEST_TIME 117 8694
TEST_TIME 118 8691
TEST_TIME 119 8675
TEST_TIME 120 8661

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-01-2023 04:38:32 to 19-01-2023 04:38:36.

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 1439241 1439241 0 0.00 0 35265 14 0 0
doc_list_biud 1439215 1439215 0 0.00 0 56621 47 0 0
sp_add_invoice_to_stock 4771 4689 82 1.71 45 257218 28116 0 82
sp_cancel_adding_invoice 5248 5248 0 0.00 5 83264 1347 0 0
sp_cancel_client_order 1639 1639 0 0.00 6 16879 466 0 0
sp_cancel_customer_reserve 517992 517992 0 0.00 1 23298 114 0 0
sp_cancel_pay_from_customer 642 642 0 0.00 4 5892 141 0 0
sp_cancel_pay_to_supplier 353 353 0 0.00 4 4378 145 0 0
sp_cancel_supplier_invoice 5805 5798 7 0.12 2 56701 1113 0 7
sp_cancel_supplier_order 399 399 0 0.00 7 1473568 187069 0 0
sp_cancel_write_off 8843 8843 0 0.00 2 17719 188 0 0
sp_client_order 19166 19166 0 0.00 6 21723 526 0 0
sp_customer_reserve 438548 438548 0 0.00 1 52529 267 0 0
sp_fill_shopping_cart 29246 29246 0 0.00 1 19764 81 0 0
sp_fill_shopping_cart_clo_res 438512 438512 0 0.00 0 14852 20 0 0
sp_get_clo_for_invoice 4641 4641 0 0.00 0 34369 337 0 0
sp_kill_cost_storno 15086 15086 0 0.00 0 15749 86 0 0
sp_kill_qstorno_ret_qs2qd 1051024 1051024 0 0.00 0 56619 50 0 0
sp_kill_qty_storno 553653 553653 0 0.00 0 56621 114 0 0
sp_lock_selected_doc 19002 19002 0 0.00 0 9253 9 0 0
sp_make_cost_storno 25861 25861 0 0.00 0 29643 90 0 0
sp_multiply_rows_for_pdistr 25859 25859 0 0.00 0 9810 47 0 0
sp_multiply_rows_for_qdistr 19166 19166 0 0.00 0 21677 209 0 0
sp_pay_from_customer 6767 6767 0 0.00 6 20832 199 0 0
sp_pay_to_supplier 5358 5358 0 0.00 7 20750 205 0 0
sp_payment_common 12125 12125 0 0.00 2 17441 54 0 0
sp_qd_handle_on_cancel_clo 1639 1639 0 0.00 1 16868 397 0 0
sp_qd_handle_on_invoice_upd_sts 9887 9887 0 0.00 0 23373 569 0 0
sp_qd_handle_on_reserve_upd_sts 9095 9095 0 0.00 0 15998 79 0 0
sp_reserve_write_off 9095 9095 0 0.00 8 24165 318 0 0
sp_supplier_invoice 5080 5080 0 0.00 9 91062 3393 0 0
sp_supplier_order 5009 5009 0 0.00 9 36201 1400 0 0
srv_aggregate_perf_data 111 111 0 0.00 2003 182742 14817 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 799 141624 34902 0 0
srv_find_qd_qs_mism 989901 989901 0 0.00 0 12487 10 0 0
srv_make_invnt_saldo 468 468 0 0.00 76 76642 4225 0 0
srv_make_money_saldo 825 825 0 0.00 1 11258 372 0 0
srv_recalc_idx_stat 5 5 0 0.00 35726 423383 219877 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4694 4694 0 0.00 1 15610 937 0 0
t$perf-norm:sp_cancel_adding_invoice 357 357 0 0.00 1 6743 543 0 0
t$perf-norm:sp_cancel_client_order 1639 1639 0 0.00 0 6527 36 0 0
t$perf-norm:sp_cancel_customer_reserve 634 634 0 0.00 0 6794 41 0 0
t$perf-norm:sp_cancel_pay_from_customer 642 642 0 0.00 0 368 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 353 353 0 0.00 0 1628 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 348 348 0 0.00 0 633 27 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 105212 7771 0 0
t$perf-norm:sp_cancel_write_off 901 901 0 0.00 0 3536 28 0 0
t$perf-norm:sp_client_order 19166 19166 0 0.00 0 7541 36 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 719 14 0 0
t$perf-norm:sp_pay_from_customer 6767 6767 0 0.00 0 4758 23 0 0
t$perf-norm:sp_pay_to_supplier 5358 5358 0 0.00 0 3746 20 0 0
t$perf-norm:sp_reserve_write_off 9095 9095 0 0.00 0 3827 25 0 0
t$perf-norm:sp_supplier_invoice 5080 5080 0 0.00 0 2971 28 0 0
t$perf-norm:sp_supplier_order 5010 5010 0 0.00 0 8558 34 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 1134 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 367 19 0 0
t$perf-norm:srv_make_invnt_saldo 468 468 0 0.00 0 1529 24 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 1968 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 206 68 0 0
v_add_invoice_to_stock 9432 9268 164 1.73 0 7146 24 0 164
v_all_customers 35650 35650 0 0.00 0 64 4 0 0
v_all_suppliers 28130 28130 0 0.00 0 82 4 0 0
v_all_wares 38332 38332 0 0.00 0 61 4 0 0
v_cancel_adding_invoice 714 714 0 0.00 0 1517 17 0 0
v_cancel_client_order 3278 3278 0 0.00 0 1179 10 0 0
v_cancel_customer_prepayment 1284 1284 0 0.00 0 1424 12 0 0
v_cancel_customer_reserve 1268 1268 0 0.00 0 5131 13 0 0
v_cancel_payment_to_supplier 706 706 0 0.00 0 2855 23 0 0
v_cancel_supplier_invoice 710 696 14 1.97 0 14694 63 0 14
v_cancel_supplier_order 798 798 0 0.00 0 1256 14 0 0
v_cancel_write_off 1802 1802 0 0.00 0 2587 27 0 0
v_max_id_clo_ord 5003 5003 0 0.00 0 601 5 0 0
v_max_id_clo_res 962 962 0 0.00 0 1948 17 0 0
v_max_id_ord_sup 5077 5077 0 0.00 0 2434 6 0 0
v_max_non_paid_invoice 1372 1372 0 0.00 0 6809 12 0 0
v_max_non_paid_realizn 3340 3340 0 0.00 0 6815 10 0 0
v_min_id_clo_ord 5003 5003 0 0.00 0 2374 8 0 0
v_min_id_clo_res 962 962 0 0.00 0 2184 10 0 0
v_min_id_ord_sup 5077 5077 0 0.00 0 3488 10 0 0
v_min_non_paid_invoice 5358 5358 0 0.00 0 17426 22 0 0
v_min_non_paid_realizn 6766 6766 0 0.00 0 9494 27 0 0
v_our_firm 9536 9536 0 0.00 0 77 4 0 0
v_reserve_write_off 18190 18190 0 0.00 0 7595 11 0 0
x_lock_depdocs_on_canc_invoice 10698 10698 0 0.00 0 12915 141 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 3292 372 0 0
x_make_qstorno_1000_1200 5009 5009 0 0.00 3 36141 1233 0 0
x_make_qstorno_1200_2000 5080 5080 0 0.00 5 90921 3165 0 0
x_make_qstorno_2100_3300 438547 438547 0 0.00 0 50088 235 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-01-2023 04:38:37 to 19-01-2023 04:38:41.

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:28 893 8192000000 8928802160 8985853952 46951904 0.569 9 8 16 0 48323040 246592 1124544
02:08:30 27720 8192000000 9725416128 10025615360 804417312 8.941 100 86 172 0 833154208 7403568 21333328
02:10:10 13930 8192000000 9767851168 10075287552 837222624 9.272 100 91 182 0 871523840 12701520 21599696
02:11:36 16389 8192000000 9794655424 10113425408 860380944 9.504 100 86 172 0 900779888 20130416 20268528
02:12:56 9580 8192000000 9869712928 10205130752 904316336 9.941 100 78 155 0 977938560 56997808 16624416
02:14:09 3311 8192000000 9915226688 10257588224 951463872 10.405 100 87 173 0 1033410160 63843472 18102816
02:15:15 1130 8192000000 9970666032 10319527936 984786064 10.731 100 89 175 0 1087161424 84311472 18063888
02:16:19 1100 8192000000 9997925760 10356781056 1021231648 11.084 100 97 185 1 1112177136 72175504 18769984
02:17:22 1325 8192000000 9993327680 10360520704 1037406128 11.240 100 87 167 1 1112497104 58372000 16718976
02:18:25 843 8192000000 10022780128 10399707136 1058460336 11.442 100 91 167 0 1144865424 68744016 17661072
02:19:26 954 8192000000 10038946928 10417844224 1076816992 11.617 100 97 183 1 1155203600 59850928 18535680
02:20:28 875 8192000000 10075585488 10463842304 1078906864 11.637 100 88 169 0 1195859856 100277920 16675072
02:21:30 1220 8192000000 10063217712 10449661952 1087168336 11.716 100 97 180 2 1182789216 77114352 18506528
02:22:32 1161 8192000000 10055968368 10441572352 1088810576 11.731 100 97 182 1 1176960720 69876144 18274000
02:23:35 1638 8192000000 10055743216 10443378688 1096713360 11.806 100 83 152 4 1173625904 62398800 14513744
02:24:37 1224 8192000000 10074811136 10462347264 1095897696 11.799 100 91 176 2 1193071440 78051008 19122736
02:25:39 1001 8192000000 10082156304 10477420544 1098883328 11.827 100 86 158 1 1200480064 85169216 16427520
02:26:41 4060 8192000000 10071444432 10470670336 1102746608 11.864 100 72 143 0 1197535040 80498432 14290000
02:27:46 1165 8192000000 10079809648 10470162432 1109243072 11.925 100 94 182 3 1197904320 71166528 17494720
02:28:48 987 8192000000 10055909040 10445324288 1095894144 11.799 100 77 148 0 1176668912 66174256 14600512
02:29:53 1350 8192000000 10076588512 10464374784 1097811408 11.817 100 93 175 2 1193645376 77734640 18099328
02:30:55 1433 8192000000 10039453200 10423443456 1088944896 11.733 100 97 180 3 1157305232 49359616 19000720
02:31:58 1199 8192000000 10045823088 10431918080 1071992960 11.571 100 77 150 0 1168121120 80810784 15317376
02:33:00 946 8192000000 10011649728 10388824064 1055795936 11.416 100 93 173 2 1135712480 61298976 18617568
02:34:02 835 8192000000 9980431504 10361200640 1043315712 11.297 100 79 153 2 1101874864 41601360 16957792
02:35:05 4640 8192000000 9986961616 10377920512 1036528576 11.231 99 49 100 1 1110050176 63714272 9807328
02:36:11 2210 8192000000 9973929104 10349223936 1037355616 11.239 99 77 146 3 1092957920 40888160 14714144
02:37:14 3388 8192000000 10004845664 10384183296 1039859376 11.263 99 73 142 2 1125544752 69436736 16248640
02:38:29 9314 8192000000 10032016160 10417561600 1063885440 11.494 100 76 147 0 1142822112 64106608 14830064
02:39:45 1489 8192000000 10054423136 10441244672 1079046512 11.638 100 78 149 2 1180395184 86428496 14920176
02:40:52 7461 8192000000 10055792384 10451636224 1086990992 11.714 100 50 93 0 1180193408 83926944 9275472
02:42:04 9135 8192000000 10135614928 10523099136 1114193776 11.972 100 76 147 0 1243183056 113214816 15774464
02:43:17 4411 8192000000 10190061536 10579980288 1122735744 12.053 100 84 160 2 1309309312 168709072 17864496
02:44:26 5865 8192000000 10207158000 10606481408 1136950448 12.187 100 66 124 1 1325828816 175392976 13485392
02:45:35 2123 8192000000 10237858272 10648813568 1146878352 12.280 100 53 94 0 1359235920 201495360 10862208
02:46:45 3955 8192000000 10302684128 10703200256 1157961600 12.384 100 69 135 0 1415443264 241789632 15692032
02:47:58 1083 8192000000 10331679696 10734354432 1180586112 12.596 100 87 168 1 1447707680 246563152 20558416
02:49:02 1754 8192000000 10400568624 10801922048 1183450096 12.622 100 93 181 1 1510550912 306466448 20634368
02:50:08 1236 8192000000 10428459552 10840772608 1183130112 12.619 100 94 178 2 1542786992 337571200 22085680
02:51:13 1443 8192000000 10486914640 10910347264 1190554704 12.689 100 95 178 1 1595983600 383652608 21776288
02:52:18 1319 8192000000 10502326496 10922905600 1185028672 12.637 100 92 181 1 1615625040 407212304 23384064
02:53:23 2029 8192000000 10525770928 10941853696 1189455312 12.678 100 93 184 2 1636453120 425398960 21598848
02:54:29 925 8192000000 10505329728 10950832128 1192563728 12.707 100 95 180 0 1613992192 400581792 20846672
02:55:32 787 8192000000 10496718416 10970329088 1198393024 12.761 100 95 187 1 1604369600 382831504 23145072
02:56:37 832 8192000000 10540740848 10968547328 1205694736 12.829 100 96 188 0 1645882752 416814400 23373616
02:57:41 1299 8192000000 10542115760 10992484352 1193282144 12.714 100 93 183 0 1646893744 431559328 22052272
02:58:46 3905 8192000000 10530337104 10979004416 1187806368 12.663 99 83 157 0 1621585776 414729616 19049792
02:59:55 1558 8192000000 10564299792 10979291136 1201910656 12.794 100 94 183 2 1667755600 444330240 21514704
03:01:01 1352 8192000000 10589577680 11028926464 1199925920 12.776 100 83 164 0 1695147360 474026464 21194976
03:02:09 1110 8192000000 10543768880 11026337792 1201581072 12.791 100 76 151 0 1649830016 429719680 18529264
03:03:13 7856 8192000000 10495552816 11001073664 1172865648 12.524 99 53 105 0 1620629456 434202544 13561264
03:04:29 3849 8192000000 10521144032 10966130688 1171049136 12.507 100 45 89 1 1621831808 439108384 11674288
03:05:44 16390 8192000000 10433629136 10927087616 1159162816 12.395 100 26 52 0 1543636128 377331472 7141840
03:07:03 18357 8192000000 10304445584 10795995136 1145952816 12.271 99 27 54 0 1409562528 256945120 6664592
03:08:33 61266 8192000000 10146982288 10590392320 1087009792 11.714 95 17 34 0 1294182720 203146528 4026400
03:10:36 3915 8192000000 10109596912 10527592448 1141633296 12.231 100 78 147 2 1206697536 49964944 15099296
03:11:44 54373 8192000000 10212205888 10658660352 1145043280 12.263 99 36 72 1 1285967648 132660384 8263984
03:14:31 48432 8192000000 10125550640 10573361152 1097590704 11.815 96 8 16 0 1209605840 109988256 2026880
03:16:31 50610 8192000000 10120058224 10566705152 1130047552 12.122 100 20 40 0 1232161344 98022816 4090976
03:18:24 46615 8192000000 10072439920 10556776448 1101396960 11.851 97 37 70 0 1179243376 70396704 7449712
03:20:23 109039 8192000000 10127590416 10587684864 1127669904 12.099 100 11 22 0 1190177728 59714304 2793520
03:23:13 58870 8192000000 10106037264 10553389056 1135444928 12.173 100 36 66 4 1224045392 80006544 8593920
03:25:16 141624 8192000000 10065273584 10569646080 1133452656 12.154 100 29 58 0 1206760928 67793936 5514336
03:28:38 126637 8192000000 10086494720 10535874560 1131013728 12.131 99 27 49 2 1184793312 48240880 5538704
03:31:46 17340 8192000000 10148037024 10579951616 1150942576 12.318 100 70 132 2 1251277792 84884016 15451200
03:33:05 38406 8192000000 10181233968 10646257664 1136427504 12.182 99 37 74 0 1268572720 123476112 8669104
03:34:49 10970 8192000000 10179287600 10650214400 1150751712 12.317 100 32 61 0 1285934176 128374544 6807920
03:36:01 93919 8192000000 10249346176 10711756800 1152935728 12.337 100 19 38 0 1320871024 163348752 4586544
03:38:57 48534 8192000000 10208295872 10762276864 1150522080 12.314 100 19 38 1 1368081376 213046176 4513120
03:41:00 69251 8192000000 10069028112 10576584704 1121238848 12.039 98 13 27 0 1253581200 129204528 3137824
03:43:13 50542 8192000000 10049680272 10488049664 1094035936 11.781 97 21 42 1 1120597168 22841536 3719696
03:45:05 14842 8192000000 10073913280 10510458880 1108889888 11.922 99 36 71 0 1163061792 47170688 7001216
03:46:21 11502 8192000000 10080901712 10506682368 1124979712 12.074 100 54 106 0 1177431872 41899968 10552192
03:47:35 45187 8192000000 10103845504 10531299328 1113487536 11.965 99 45 79 0 1202819552 81458464 7873552
03:49:20 30831 8192000000 10085194464 10512035840 1109494032 11.928 98 60 119 2 1148599568 27758032 11347504
03:50:54 13350 8192000000 10133196816 10552111104 1097260288 11.812 98 47 94 0 1196805520 89952000 9593232
03:52:13 57383 8192000000 10078588368 10564411392 1108042944 11.914 99 24 48 0 1207319184 93335632 5940608
03:54:26 115607 8192000000 10066666352 10532728832 1127331520 12.096 99 28 56 0 1209959440 76438624 6189296
03:57:49 59005 8192000000 10086025840 10504650752 1112258416 11.954 98 49 99 0 1163438224 40897984 10281824
03:59:49 16883 8192000000 10106265456 10541568000 1126909360 12.092 99 55 102 3 1199076352 61611040 10555952
04:01:07 60007 8192000000 10060835488 10517905408 1095761824 11.797 97 28 53 2 1166338544 65253376 5323344
04:03:08 32966 8192000000 10047891376 10504785920 1081177632 11.659 97 22 44 1 1137077856 50842800 5057424
04:04:43 71621 8192000000 10033144144 10499309568 1050945232 11.370 94 21 42 0 1094112256 38298688 4868336
04:07:00 61409 8192000000 10075999776 10537410560 1104655312 11.882 99 29 54 2 1160335056 49965296 5714448
04:10:32 38328 8192000000 10090412000 10530594816 1125492560 12.079 99 27 54 0 1177542064 46675280 5374224
04:12:12 20944 8192000000 10121698112 10556571648 1125625792 12.080 99 39 74 0 1208594160 74814992 8153376
04:13:40 53574 8192000000 10116775952 10586566656 1091736272 11.759 96 24 48 0 1208329968 111262896 5330800
04:15:40 62941 8192000000 10127697888 10574802944 1107229280 11.906 98 21 42 0 1206291872 94309552 4753040
04:17:44 47163 8192000000 10134450768 10573275136 1125631968 12.080 99 29 58 0 1265186352 132810480 6743904
04:19:49 63040 8192000000 10066518160 10555056128 1120383440 12.031 99 23 44 0 1206968480 82101360 4483680
04:21:58 40401 8192000000 10180670608 10638757888 1133185088 12.151 99 36 70 1 1271293632 130495600 7612944
04:23:41 56795 8192000000 10084543088 10520449024 1125499216 12.079 99 44 88 1 1174078256 39584016 8995024
04:25:42 53438 8192000000 10087164480 10522419200 1116683488 11.996 98 45 87 0 1169147568 43881488 8582592
04:27:36 57268 8192000000 10095469760 10561880064 1141461280 12.229 100 21 42 0 1224689616 78921536 4306800
04:30:44 88322 8192000000 10074063024 10568327168 1112340992 11.955 97 12 24 0 1158334256 43004784 2988480
04:33:12 59798 8192000000 10079459840 10541498368 1078032448 11.629 94 17 35 0 1142788016 61088160 3667408
04:35:14 58901 8192000000 10113972544 10563690496 1109479904 11.927 98 19 38 0 1167458800 53861184 4117712
04:37:23 10171 8192000000 9231918672 9567117312 356809136 4.173 32 4 8 0 393136368 35634464 692768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-01-2023 04:38:43 to 19-01-2023 04:38:59.

Exceptions occured during test was in run

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

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

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          209024
MON$OLDEST_ACTIVE               209628
MON$OLDEST_SNAPSHOT             209628
MON$NEXT_TRANSACTION            209628
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                       2048928
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             757
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 ---
:847: 
:848: mscs-test-161.budzdorov.ru    Thu Jan 19 04:37:41 2023
:849:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:850: 
--- end of diff output ---

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

19.01.23 04:39:03. 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-19 04:39:06 No severe PSQL-related problems occured
19.01.23 04:39:07. End of report.