Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.10.2022 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 15182300 kB
MemAvailable 30427132 kB
Buffers 20 kB
Cached 16495208 kB
SwapCached 1956 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 2022-10-27 04:37:23
DTS_END                         2022-10-27 04:37:23.0950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47812
TRN_ID                          215851
ATT_ID                          633
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: 8718.78 24355 1046254
customer order: creation 165.99 461 19919
customer order: refuse 13.99 425 1679
order to supplier: creation 43.81 1287 5257
order to supplier: removal 3.13 187511 375
invoice (draft): creation 45.01 3275 5401
invoice (draft): removal 47.89 954 5747
invoice accept: apply 41.16 26535 4939
invoice accept: cancel 43.32 1273 5198
customer reserve: creation 3768.45 256 452214
customer reserve: removal 4259.87 110 511184
realization accept: apply 81.42 298 9770
realization accept: cancel 78.58 177 9430
payment from customer: creation 57.53 190 6903
payment from customer: removal 5.58 136 669
payment to supplier: creation 47.53 195 5703
payment to supplier: removal 3.09 116 371
service: total inventory turnovers 4.06 3771 487
service: total monetary turnovers 7.43 343 891
service: aggregate perf. data 0.93 14966 112
service: refresh index statistics 0.04 244811 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-10-2022 04:39:03 to 27-10-2022 04:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4794
TEST_TIME 2 5119
TEST_TIME 3 5217
TEST_TIME 4 6562
TEST_TIME 5 7243
TEST_TIME 6 6945
TEST_TIME 7 6604
TEST_TIME 8 7075
TEST_TIME 9 7177
TEST_TIME 10 7377
TEST_TIME 11 7400
TEST_TIME 12 7362
TEST_TIME 13 7509
TEST_TIME 14 7628
TEST_TIME 15 7669
TEST_TIME 16 7633
TEST_TIME 17 7711
TEST_TIME 18 7649
TEST_TIME 19 7499
TEST_TIME 20 7480
TEST_TIME 21 7801
TEST_TIME 22 7696
TEST_TIME 23 7874
TEST_TIME 24 8079
TEST_TIME 25 8439
TEST_TIME 26 8580
TEST_TIME 27 8815
TEST_TIME 28 9204
TEST_TIME 29 9569
TEST_TIME 30 9978
TEST_TIME 31 10184
TEST_TIME 32 10638
TEST_TIME 33 10649
TEST_TIME 34 10473
TEST_TIME 35 10327
TEST_TIME 36 10340
TEST_TIME 37 10181
TEST_TIME 38 10002
TEST_TIME 39 9933
TEST_TIME 40 9899
TEST_TIME 41 9800
TEST_TIME 42 9660
TEST_TIME 43 9679
TEST_TIME 44 9626
TEST_TIME 45 9491
TEST_TIME 46 9348
TEST_TIME 47 9335
TEST_TIME 48 9235
TEST_TIME 49 9226
TEST_TIME 50 9103
TEST_TIME 51 9037
TEST_TIME 52 8948
TEST_TIME 53 8850
TEST_TIME 54 8743
TEST_TIME 55 8709
TEST_TIME 56 8569
TEST_TIME 57 8618
TEST_TIME 58 8722
TEST_TIME 59 8746
TEST_TIME 60 8719
TEST_TIME 61 8748
TEST_TIME 62 8803
TEST_TIME 63 8869
TEST_TIME 64 8879
TEST_TIME 65 8899
TEST_TIME 66 8989
TEST_TIME 67 8957
TEST_TIME 68 8903
TEST_TIME 69 8922
TEST_TIME 70 9004
TEST_TIME 71 8951
TEST_TIME 72 8872
TEST_TIME 73 8846
TEST_TIME 74 8789
TEST_TIME 75 8805
TEST_TIME 76 8780
TEST_TIME 77 8735
TEST_TIME 78 8670
TEST_TIME 79 8638
TEST_TIME 80 8630
TEST_TIME 81 8625
TEST_TIME 82 8618
TEST_TIME 83 8599
TEST_TIME 84 8610
TEST_TIME 85 8600
TEST_TIME 86 8570
TEST_TIME 87 8579
TEST_TIME 88 8553
TEST_TIME 89 8622
TEST_TIME 90 8634
TEST_TIME 91 8636
TEST_TIME 92 8665
TEST_TIME 93 8772
TEST_TIME 94 8741
TEST_TIME 95 8918
TEST_TIME 96 8982
TEST_TIME 97 9029
TEST_TIME 98 9018
TEST_TIME 99 9012
TEST_TIME 100 8965
TEST_TIME 101 8938
TEST_TIME 102 8937
TEST_TIME 103 8924
TEST_TIME 104 9003
TEST_TIME 105 9004
TEST_TIME 106 9051
TEST_TIME 107 9029
TEST_TIME 108 8994
TEST_TIME 109 8982
TEST_TIME 110 8941
TEST_TIME 111 8895
TEST_TIME 112 8884
TEST_TIME 113 8865
TEST_TIME 114 8835
TEST_TIME 115 8818
TEST_TIME 116 8779
TEST_TIME 117 8734
TEST_TIME 118 8785
TEST_TIME 119 8785
TEST_TIME 120 8736

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-10-2022 04:39:06 to 27-10-2022 04:39:10.

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 1463015 1463015 0 0.00 0 51106 13 0 0
doc_list_biud 1463001 1463001 0 0.00 0 31011 44 0 0
sp_add_invoice_to_stock 5044 4939 105 2.08 40 243074 26536 0 105
sp_cancel_adding_invoice 5198 5198 0 0.00 6 83526 1273 0 0
sp_cancel_client_order 1679 1679 0 0.00 7 24855 426 0 0
sp_cancel_customer_reserve 511184 511184 0 0.00 1 29992 111 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 4 6149 136 0 0
sp_cancel_pay_to_supplier 371 371 0 0.00 4 2213 116 0 0
sp_cancel_supplier_invoice 5757 5747 10 0.17 3 17475 954 0 10
sp_cancel_supplier_order 375 375 0 0.00 13 1347976 187512 0 0
sp_cancel_write_off 9430 9430 0 0.00 2 12237 178 0 0
sp_client_order 19919 19919 0 0.00 5 25162 461 0 0
sp_customer_reserve 452214 452214 0 0.00 0 66588 257 0 0
sp_fill_shopping_cart 30561 30561 0 0.00 1 16701 86 0 0
sp_fill_shopping_cart_clo_res 452190 452190 0 0.00 0 41291 20 0 0
sp_get_clo_for_invoice 4907 4907 0 0.00 0 37313 339 0 0
sp_kill_cost_storno 15668 15668 0 0.00 0 12162 74 0 0
sp_kill_qstorno_ret_qs2qd 1037920 1037920 0 0.00 0 21776 48 0 0
sp_kill_qty_storno 548287 548287 0 0.00 0 31010 108 0 0
sp_lock_selected_doc 20201 20201 0 0.00 0 8898 9 0 0
sp_make_cost_storno 27282 27282 0 0.00 0 51084 87 0 0
sp_multiply_rows_for_pdistr 27279 27279 0 0.00 0 9113 38 0 0
sp_multiply_rows_for_qdistr 19918 19918 0 0.00 0 12773 171 0 0
sp_pay_from_customer 6903 6903 0 0.00 5 16307 191 0 0
sp_pay_to_supplier 5703 5703 0 0.00 6 13204 195 0 0
sp_payment_common 12604 12604 0 0.00 2 9302 48 0 0
sp_qd_handle_on_cancel_clo 1679 1679 0 0.00 0 24845 342 0 0
sp_qd_handle_on_invoice_upd_sts 10103 10103 0 0.00 0 31010 514 0 0
sp_qd_handle_on_reserve_upd_sts 9769 9769 0 0.00 0 20619 72 0 0
sp_reserve_write_off 9770 9770 0 0.00 8 21152 299 0 0
sp_supplier_invoice 5401 5401 0 0.00 9 88586 3275 0 0
sp_supplier_order 5257 5257 0 0.00 8 18488 1288 0 0
srv_aggregate_perf_data 112 112 0 0.00 2239 129985 14967 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 794 150341 35691 0 0
srv_find_qd_qs_mism 998838 998838 0 0.00 0 25432 10 0 0
srv_make_invnt_saldo 487 487 0 0.00 44 53077 3772 0 0
srv_make_money_saldo 891 891 0 0.00 1 7825 343 0 0
srv_recalc_idx_stat 5 5 0 0.00 94241 429374 244811 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4943 4943 0 0.00 0 9771 907 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 4566 469 0 0
t$perf-norm:sp_cancel_client_order 1679 1679 0 0.00 0 2889 31 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 2481 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 567 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 371 371 0 0.00 0 4265 28 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 2208 38 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 117592 6829 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 17232 43 0 0
t$perf-norm:sp_client_order 19920 19920 0 0.00 0 17713 41 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 10181 24 0 0
t$perf-norm:sp_pay_from_customer 6903 6903 0 0.00 0 2965 20 0 0
t$perf-norm:sp_pay_to_supplier 5704 5704 0 0.00 0 11895 22 0 0
t$perf-norm:sp_reserve_write_off 9771 9771 0 0.00 0 6473 26 0 0
t$perf-norm:sp_supplier_invoice 5402 5402 0 0.00 0 14129 35 0 0
t$perf-norm:sp_supplier_order 5258 5258 0 0.00 0 15753 34 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 149 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 64 5 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 2199 19 0 0
t$perf-norm:srv_make_money_saldo 891 891 0 0.00 0 640 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 170 82 0 0
v_add_invoice_to_stock 10008 9798 210 2.09 0 7357 28 0 210
v_all_customers 36285 36285 0 0.00 0 59 4 0 0
v_all_suppliers 29688 29688 0 0.00 0 77 4 0 0
v_all_wares 39823 39823 0 0.00 0 120 4 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 7346 25 0 0
v_cancel_client_order 3358 3358 0 0.00 0 11288 15 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 2063 11 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 1 5667 16 0 0
v_cancel_payment_to_supplier 742 742 0 0.00 0 2185 13 0 0
v_cancel_supplier_invoice 768 748 20 2.60 0 8486 43 0 20
v_cancel_supplier_order 750 750 0 0.00 0 5843 24 0 0
v_cancel_write_off 2012 2012 0 0.00 0 9315 37 0 0
v_max_id_clo_ord 5251 5251 0 0.00 0 4728 6 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 3004 14 0 0
v_max_id_ord_sup 5394 5394 0 0.00 0 4609 8 0 0
v_max_non_paid_invoice 1502 1502 0 0.00 0 87 8 0 0
v_max_non_paid_realizn 3724 3724 0 0.00 0 5367 9 0 0
v_min_id_clo_ord 5251 5251 0 0.00 0 2512 11 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 12629 37 0 0
v_min_id_ord_sup 5394 5394 0 0.00 0 10237 15 0 0
v_min_non_paid_invoice 5701 5701 0 0.00 0 7651 17 0 0
v_min_non_paid_realizn 6903 6903 0 0.00 0 9292 18 0 0
v_our_firm 9886 9886 0 0.00 0 60 5 0 0
v_reserve_write_off 19536 19536 0 0.00 0 11301 14 0 0
x_lock_depdocs_on_canc_invoice 10571 10571 0 0.00 0 34444 130 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 11525 408 0 0
x_make_qstorno_1000_1200 5257 5257 0 0.00 2 18417 1115 0 0
x_make_qstorno_1200_2000 5401 5401 0 0.00 4 81060 3040 0 0
x_make_qstorno_2100_3300 452212 452212 0 0.00 0 66584 224 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-10-2022 04:39:11 to 27-10-2022 04:39:15.

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:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:29 24556 8192000000 9732616624 10034556928 809877696 8.996 100 88 176 0 838191792 7502800 20811296
02:10:02 22309 8192000000 9772948832 10085433344 845653616 9.357 100 87 174 0 879627648 13448112 20525920
02:11:30 14215 8192000000 9804612864 10125025280 870637840 9.606 100 82 164 0 913538784 23988880 18912064
02:13:02 8252 8192000000 9877501808 10215616512 926469024 10.160 100 80 154 0 988021568 45201728 16350816
02:14:14 2660 8192000000 9928351648 10274881536 964023232 10.528 100 86 163 1 1047214064 65867104 17323728
02:15:18 2008 8192000000 9972394496 10330775552 991980864 10.801 100 94 176 2 1086214304 75241648 18991792
02:16:22 3593 8192000000 9984891536 10349604864 1018861008 11.061 100 80 154 0 1106070768 72136480 15073280
02:17:28 1213 8192000000 10017812288 10389778432 1047415424 11.336 100 93 185 0 1138334640 71598672 19320544
02:18:31 1230 8192000000 10036595776 10410889216 1056658016 11.424 100 95 184 2 1148130912 72397408 19075488
02:19:34 1067 8192000000 10018581104 10393440256 1063771200 11.493 100 94 184 1 1139392736 58052208 17569328
02:20:36 1340 8192000000 10041556096 10420498432 1069512448 11.547 100 90 173 1 1157380624 69649952 18218224
02:21:38 1102 8192000000 10067586960 10449293312 1076089152 11.610 100 94 183 0 1182148624 88418816 17640656
02:22:40 1135 8192000000 10046472640 10438184960 1080795024 11.655 100 97 189 2 1173645504 73206672 19643808
02:23:42 1053 8192000000 10058888704 10448723968 1085883152 11.703 100 89 177 1 1183606080 79988416 17734512
02:24:44 3831 8192000000 10059520688 10451939328 1091506160 11.757 100 69 137 1 1178401168 73439328 13455680
02:25:49 1147 8192000000 10067838864 10457759744 1099669968 11.835 100 94 181 2 1189452592 71407120 18375504
02:26:52 1185 8192000000 10082932128 10472366080 1100795280 11.845 100 98 187 0 1204919264 83918272 20205712
02:27:54 1065 8192000000 10052084624 10444455936 1092017120 11.762 99 76 149 0 1176096944 69186464 14893360
02:28:56 1059 8192000000 10033117936 10420006912 1099534384 11.833 100 94 179 2 1153482128 35884512 18063232
02:29:58 901 8192000000 10050358592 10440708096 1074721456 11.597 100 84 145 5 1166939840 77907696 14310688
02:31:00 1012 8192000000 10031780848 10422226944 1076522608 11.614 99 91 175 1 1154287952 60327104 17438240
02:32:02 1352 8192000000 10032886304 10412027904 1064700528 11.501 100 95 180 3 1154186960 70975584 18510848
02:33:04 6871 8192000000 10008563808 10399215616 1061033392 11.466 99 59 115 0 1113531584 41381232 11116960
02:34:12 1349 8192000000 10006482672 10382749696 1038885776 11.254 99 82 152 1 1128715264 73790160 16039328
02:35:14 2609 8192000000 9966906768 10342518784 1024647728 11.117 99 70 128 1 1089532176 49179680 15704768
02:36:19 6118 8192000000 10001993936 10384449536 1038566144 11.251 100 56 110 0 1110406368 61125168 10715056
02:37:28 12612 8192000000 9970772624 10339700736 1042558800 11.289 100 77 137 4 1085143376 29591536 12993040
02:38:47 14660 8192000000 10017085584 10396069888 1062976656 11.485 100 70 126 0 1135577280 59738480 12862144
02:40:10 7076 8192000000 10021029920 10408714240 1070500864 11.557 100 64 115 2 1147170448 65159744 11509840
02:41:20 1100 8192000000 10084767824 10465251328 1090716752 11.749 100 91 177 0 1201099600 91192128 19190720
02:42:24 1198 8192000000 10124585472 10513518592 1112126976 11.953 100 85 160 2 1239305024 111132960 16045088
02:43:29 6503 8192000000 10142098512 10535493632 1124426656 12.069 100 70 136 2 1258413488 119948848 14037984
02:44:47 4115 8192000000 10188452528 10585862144 1132561456 12.146 100 70 133 1 1299859904 152601504 14696944
02:45:57 2081 8192000000 10198745040 10605338624 1143393264 12.247 100 81 154 0 1316843552 155804544 17645744
02:47:02 3980 8192000000 10189994832 10603655168 1150943952 12.318 100 84 159 2 1324857856 156512784 17401120
02:48:09 2899 8192000000 10208984816 10614235136 1156807360 12.373 100 76 152 0 1318905840 145559360 16539120
02:49:22 1756 8192000000 10245234544 10650021888 1169419520 12.491 100 86 165 0 1362037008 173660096 18957392
02:50:28 1569 8192000000 10244929568 10656735232 1177563152 12.567 100 91 173 3 1354184384 158785216 17836016
02:51:32 887 8192000000 10324919840 10731802624 1186235792 12.648 100 95 187 0 1435795904 228053808 21506304
02:52:36 1371 8192000000 10333103856 10742464512 1189569056 12.679 100 99 192 0 1442752832 231171680 22012096
02:53:41 1507 8192000000 10382772976 10788716544 1193295840 12.714 100 96 179 0 1496116384 281862144 20958400
02:54:45 1305 8192000000 10386308128 10792964096 1187473712 12.660 100 92 182 0 1496274352 288408928 20391712
02:55:51 1142 8192000000 10443317808 10853740544 1208913280 12.859 100 95 185 0 1551832976 319908976 23010720
02:56:57 1359 8192000000 10486557632 10918166528 1200925824 12.785 100 92 173 0 1592069472 370180112 20963536
02:58:02 1158 8192000000 10500337792 10955235328 1205619568 12.828 100 93 180 1 1601071168 373998992 21452608
02:59:05 787 8192000000 10547687344 10973949952 1217158592 12.935 100 95 188 1 1651619824 411781008 22680224
03:00:10 815 8192000000 10572811856 11001606144 1203342544 12.807 100 98 188 0 1678579632 450496624 24740464
03:01:13 1530 8192000000 10577594336 11052306432 1205526944 12.828 100 72 136 1 1683611104 459753200 18330960
03:02:23 5350 8192000000 10548763136 10996441088 1175403888 12.547 100 60 118 1 1651905504 460662592 15839024
03:03:36 15115 8192000000 10514562688 10981023744 1160847392 12.411 99 34 68 0 1642103008 471985488 9270128
03:05:09 22157 8192000000 10444636400 10907803648 1152200624 12.330 100 26 52 0 1577544176 418076544 7267008
03:06:49 38446 8192000000 10298595456 10805022720 1117931792 12.007 98 18 35 0 1457378352 335108816 4337744
03:09:34 24304 8192000000 10068152480 10529054720 1090469504 11.747 97 26 50 0 1154658976 59016304 5173168
03:11:00 93980 8192000000 10052120128 10470690816 1064555840 11.500 96 31 60 0 1129747904 58706448 6485616
03:13:34 86096 8192000000 10063824000 10511605760 1090721376 11.750 98 27 52 0 1157758112 61811664 5225072
03:16:00 99204 8192000000 10080799328 10579984384 1111715504 11.949 99 15 29 0 1182821152 68206752 2898896
03:18:40 150341 8192000000 10082653904 10578194432 1106356880 11.898 98 10 19 0 1176254576 68093840 1803856
03:22:11 107941 8192000000 10058888800 10529345536 1111696880 11.948 98 33 67 0 1169852720 50659056 7496784
03:25:18 21276 8192000000 10066497632 10503958528 1133118736 12.151 100 47 76 3 1174933696 33936336 7878624
03:26:40 42356 8192000000 10040301824 10468253696 1085056672 11.696 96 43 80 1 1117635552 23804816 8774064
03:28:27 35170 8192000000 10050406624 10493562880 1115095072 11.981 100 14 28 0 1157380352 39021728 3263552
03:30:10 63060 8192000000 10084412912 10530615296 1132805136 12.148 100 18 36 0 1195644368 58586560 4252672
03:32:24 101978 8192000000 10170378064 10643980288 1129212480 12.114 99 32 63 1 1263168304 126517392 7438432
03:35:07 55427 8192000000 10069351280 10568355840 1125419968 12.078 100 9 18 0 1193255472 66017504 1818000
03:37:02 56716 8192000000 10103565792 10544685056 1123986464 12.065 99 18 34 1 1194244320 66735024 3522832
03:39:20 32762 8192000000 10120409232 10558418944 1140972512 12.225 100 19 38 0 1230307136 85186560 4148064
03:40:56 20207 8192000000 10134203328 10566516736 1137318832 12.190 99 48 94 1 1195486016 48283424 9883760
03:42:23 100704 8192000000 10071992208 10536357888 1119425424 12.022 98 18 36 0 1173056928 50437104 3194400
03:45:12 9562 8192000000 10124598704 10538340352 1146225728 12.274 100 67 133 1 1215438080 55727152 13485200
03:46:24 29802 8192000000 10140053520 10560647168 1115730016 11.987 99 44 88 0 1223805184 99258832 8816336
03:47:54 10103 8192000000 10069281904 10495025152 1109268672 11.925 98 39 74 1 1148138416 30981216 7888528
03:49:06 95255 8192000000 10074309360 10514063360 1122769632 12.053 99 42 84 1 1193191200 61878608 8542960
03:51:45 5135 8192000000 10090491984 10543382528 1142844096 12.242 100 25 43 0 1196003872 48523728 4636048
03:52:51 35687 8192000000 10070062064 10579173376 1138260816 12.199 100 8 17 0 1190868624 51015024 1592784
03:54:32 63892 8192000000 10043977184 10494042112 1089548400 11.738 96 19 39 1 1133961744 39671648 4741696
03:56:38 45859 8192000000 10055141760 10539294720 1102411968 11.861 98 11 22 0 1153805616 49079248 2314400
03:58:28 31274 8192000000 10066286512 10516946944 1104956640 11.885 98 24 48 0 1145451568 35691168 4803760
04:00:01 32206 8192000000 10085685984 10531024896 1104874624 11.884 98 26 48 0 1185018496 74898336 5245536
04:01:40 32280 8192000000 10139513792 10584002560 1098178992 11.820 96 33 66 0 1193955728 88386944 7389792
04:03:13 13822 8192000000 10213452128 10639351808 1145234688 12.265 100 45 90 0 1301232464 145956544 10041232
04:04:34 27003 8192000000 10232626000 10677366784 1118579424 12.014 98 44 87 0 1307292464 178394736 10318304
04:06:02 37832 8192000000 10291047776 10747416576 1142303264 12.237 100 42 85 0 1384047456 230909728 10834464
04:07:50 33931 8192000000 10268785616 10720456704 1111203856 11.944 97 29 58 0 1340630096 222550384 6875856
04:09:31 72505 8192000000 10235341392 10763759616 1110213360 11.934 97 20 40 0 1391042528 275875536 4953632
04:12:17 72368 8192000000 10151692720 10646237184 1100903744 11.846 98 9 18 0 1265744320 162885040 1955536
04:14:30 84369 8192000000 10091253248 10548944896 1107772000 11.911 98 14 28 0 1159108960 48907840 2429120
04:16:55 76753 8192000000 10114266272 10549854208 1117424256 12.003 98 37 71 0 1189459488 64987312 7047920
04:19:15 17913 8192000000 10150147248 10599350272 1146192608 12.274 100 59 114 1 1235527280 77233168 12101504
04:20:37 38842 8192000000 10122927808 10601922560 1136006192 12.178 100 47 92 0 1242617472 97819200 8792080
04:22:22 10613 8192000000 10141028784 10573496320 1144057888 12.254 100 58 110 1 1223755088 68887728 10809472
04:23:35 62062 8192000000 10090607584 10535034880 1101445232 11.851 96 29 56 0 1150010624 43236288 5329104
04:25:39 79725 8192000000 10084719520 10562998272 1135648976 12.175 100 37 73 0 1207937680 64612032 7676672
04:27:59 43301 8192000000 10068699088 10495827968 1107923216 11.913 97 48 91 1 1148823088 31271552 9628320
04:29:43 42892 8192000000 10084866176 10548584448 1113395888 11.965 98 25 51 0 1176206720 57344992 5465840
04:31:31 56342 8192000000 10114913648 10578173952 1145484992 12.267 100 19 38 0 1191683952 41928512 4270448
04:33:52 52639 8192000000 10154963664 10617466880 1140169200 12.217 100 17 34 0 1247935152 103506928 4259024
04:35:52 75840 8192000000 10078331696 10533572608 1135456512 12.173 100 19 38 0 1189881088 50567728 3856848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-10-2022 04:39:17 to 27-10-2022 04:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-10-2022 04:39:34 to 27-10-2022 04:39:36.

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          213942
MON$OLDEST_ACTIVE               215984
MON$OLDEST_SNAPSHOT             215984
MON$NEXT_TRANSACTION            215984
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                       2054624
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2863 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2863 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2863 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2863 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 ---
:45: 
:46: mscs-test-161.budzdorov.ru    Thu Oct 27 04:37:25 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Thu Oct 27 04:37:26 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Thu Oct 27 04:37:34 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Thu Oct 27 04:37:34 2022
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Thu Oct 27 04:37:38 2022
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Thu Oct 27 04:37:38 2022
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
--- end of diff output ---

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

27.10.22 04:39:37. 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-10-27 04:39:40 No severe PSQL-related problems occured
27.10.22 04:39:41. End of report.