Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.09.2022 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15702708 kB
MemAvailable 30580104 kB
Buffers 0 kB
Cached 15998808 kB
SwapCached 2028 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-09-25 10:37:55
DTS_END                         2022-09-25 10:37:21.6260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40472
TRN_ID                          220664
ATT_ID                          259
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8718.88 27117 1046265
customer order: creation 160.46 471 19255
customer order: refuse 13.96 384 1675
order to supplier: creation 44.38 1277 5325
order to supplier: removal 3.46 140133 415
invoice (draft): creation 45.10 3260 5412
invoice (draft): removal 48.32 971 5798
invoice accept: apply 40.78 23888 4894
invoice accept: cancel 43.57 1189 5228
customer reserve: creation 3774.13 230 452896
customer reserve: removal 4261.98 89 511437
realization accept: apply 80.08 254 9609
realization accept: cancel 77.39 156 9287
payment from customer: creation 56.46 147 6775
payment from customer: removal 5.79 125 695
payment to supplier: creation 47.37 177 5684
payment to supplier: removal 3.61 121 433
service: total inventory turnovers 3.88 3435 466
service: total monetary turnovers 7.19 352 863
service: aggregate perf. data 0.95 14812 114
service: refresh index statistics 0.03 350876 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-09-2022 10:38:42 to 25-09-2022 10:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6479
TEST_TIME 2 5679
TEST_TIME 3 5933
TEST_TIME 4 5702
TEST_TIME 5 5923
TEST_TIME 6 5835
TEST_TIME 7 6047
TEST_TIME 8 6078
TEST_TIME 9 6138
TEST_TIME 10 6271
TEST_TIME 11 6391
TEST_TIME 12 6695
TEST_TIME 13 6733
TEST_TIME 14 6846
TEST_TIME 15 7021
TEST_TIME 16 6988
TEST_TIME 17 7243
TEST_TIME 18 7371
TEST_TIME 19 7469
TEST_TIME 20 8240
TEST_TIME 21 8490
TEST_TIME 22 8550
TEST_TIME 23 8843
TEST_TIME 24 9174
TEST_TIME 25 9690
TEST_TIME 26 9922
TEST_TIME 27 9878
TEST_TIME 28 10572
TEST_TIME 29 10684
TEST_TIME 30 10755
TEST_TIME 31 10561
TEST_TIME 32 10375
TEST_TIME 33 10160
TEST_TIME 34 9987
TEST_TIME 35 9990
TEST_TIME 36 10188
TEST_TIME 37 10091
TEST_TIME 38 9961
TEST_TIME 39 9921
TEST_TIME 40 9813
TEST_TIME 41 9747
TEST_TIME 42 9737
TEST_TIME 43 9802
TEST_TIME 44 9690
TEST_TIME 45 9595
TEST_TIME 46 9591
TEST_TIME 47 9576
TEST_TIME 48 9626
TEST_TIME 49 9612
TEST_TIME 50 9608
TEST_TIME 51 9695
TEST_TIME 52 9582
TEST_TIME 53 9543
TEST_TIME 54 9723
TEST_TIME 55 9695
TEST_TIME 56 9760
TEST_TIME 57 9770
TEST_TIME 58 9826
TEST_TIME 59 9802
TEST_TIME 60 9666
TEST_TIME 61 9532
TEST_TIME 62 9468
TEST_TIME 63 9423
TEST_TIME 64 9422
TEST_TIME 65 9352
TEST_TIME 66 9313
TEST_TIME 67 9211
TEST_TIME 68 9189
TEST_TIME 69 9161
TEST_TIME 70 9157
TEST_TIME 71 9240
TEST_TIME 72 9178
TEST_TIME 73 9170
TEST_TIME 74 9197
TEST_TIME 75 9240
TEST_TIME 76 9298
TEST_TIME 77 9250
TEST_TIME 78 9230
TEST_TIME 79 9247
TEST_TIME 80 9247
TEST_TIME 81 9369
TEST_TIME 82 9484
TEST_TIME 83 9373
TEST_TIME 84 9272
TEST_TIME 85 9271
TEST_TIME 86 9261
TEST_TIME 87 9200
TEST_TIME 88 9174
TEST_TIME 89 9138
TEST_TIME 90 9069
TEST_TIME 91 9047
TEST_TIME 92 9019
TEST_TIME 93 8997
TEST_TIME 94 8930
TEST_TIME 95 8935
TEST_TIME 96 8924
TEST_TIME 97 8916
TEST_TIME 98 8943
TEST_TIME 99 8906
TEST_TIME 100 8911
TEST_TIME 101 8929
TEST_TIME 102 8932
TEST_TIME 103 8979
TEST_TIME 104 8955
TEST_TIME 105 8948
TEST_TIME 106 8977
TEST_TIME 107 9023
TEST_TIME 108 9036
TEST_TIME 109 8962
TEST_TIME 110 8943
TEST_TIME 111 8948
TEST_TIME 112 8925
TEST_TIME 113 8877
TEST_TIME 114 8847
TEST_TIME 115 8836
TEST_TIME 116 8828
TEST_TIME 117 8828
TEST_TIME 118 8814
TEST_TIME 119 8796
TEST_TIME 120 8744

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-09-2022 10:38:45 to 25-09-2022 10:38:49.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1463582 1463582 0 0.00 0 19679 12 0 0
doc_list_biud 1463546 1463546 0 0.00 0 29127 37 0 0
sp_add_invoice_to_stock 5059 4894 165 3.26 47 225087 23889 0 165
sp_cancel_adding_invoice 5228 5228 0 0.00 6 71341 1190 0 0
sp_cancel_client_order 1675 1675 0 0.00 5 10056 385 0 0
sp_cancel_customer_reserve 511437 511437 0 0.00 1 28776 90 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 8550 125 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 4 5466 122 0 0
sp_cancel_supplier_invoice 5812 5798 14 0.24 2 24378 972 0 14
sp_cancel_supplier_order 415 415 0 0.00 11 1238997 140134 0 0
sp_cancel_write_off 9287 9287 0 0.00 1 18991 156 0 0
sp_client_order 19255 19255 0 0.00 6 27546 472 0 0
sp_customer_reserve 452896 452896 0 0.00 0 142717 230 0 0
sp_fill_shopping_cart 29986 29986 0 0.00 1 23097 71 0 0
sp_fill_shopping_cart_clo_res 452857 452857 0 0.00 0 16985 18 0 0
sp_get_clo_for_invoice 4851 4851 0 0.00 0 29588 318 0 0
sp_kill_cost_storno 15643 15643 0 0.00 0 9729 67 0 0
sp_kill_qstorno_ret_qs2qd 1038374 1038374 0 0.00 0 28755 41 0 0
sp_kill_qty_storno 548300 548300 0 0.00 0 29122 93 0 0
sp_lock_selected_doc 20149 20149 0 0.00 0 8019 7 0 0
sp_make_cost_storno 26919 26919 0 0.00 0 15691 70 0 0
sp_multiply_rows_for_pdistr 26919 26919 0 0.00 0 9524 34 0 0
sp_multiply_rows_for_qdistr 19255 19255 0 0.00 0 25576 206 0 0
sp_pay_from_customer 6775 6775 0 0.00 5 11821 148 0 0
sp_pay_to_supplier 5684 5684 0 0.00 6 13378 177 0 0
sp_payment_common 12459 12459 0 0.00 2 9522 38 0 0
sp_qd_handle_on_cancel_clo 1675 1675 0 0.00 0 9705 315 0 0
sp_qd_handle_on_invoice_upd_sts 10079 10079 0 0.00 0 29114 455 0 0
sp_qd_handle_on_reserve_upd_sts 9609 9609 0 0.00 0 13367 62 0 0
sp_reserve_write_off 9609 9609 0 0.00 8 23510 255 0 0
sp_supplier_invoice 5412 5412 0 0.00 8 89080 3261 0 0
sp_supplier_order 5325 5325 0 0.00 7 41344 1277 0 0
srv_aggregate_perf_data 114 114 0 0.00 2306 144195 14812 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 789 184640 38956 0 0
srv_find_qd_qs_mism 999151 999151 0 0.00 0 17023 10 0 0
srv_make_invnt_saldo 466 466 0 0.00 24 81083 3435 0 0
srv_make_money_saldo 863 863 0 0.00 1 19069 353 0 0
srv_recalc_idx_stat 4 4 0 0.00 168730 548084 350876 0 0
t$perf-abend:sp_add_invoice_to_stock 165 165 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4894 4894 0 0.00 1 10036 670 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 2 5956 377 0 0
t$perf-norm:sp_cancel_client_order 1675 1675 0 0.00 0 8560 38 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 3903 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1186 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 6588 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 1428 26 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 79727 5220 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 1868 17 0 0
t$perf-norm:sp_client_order 19255 19255 0 0.00 0 11536 31 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 3859 19 0 0
t$perf-norm:sp_pay_from_customer 6775 6775 0 0.00 0 7997 21 0 0
t$perf-norm:sp_pay_to_supplier 5684 5684 0 0.00 0 6589 18 0 0
t$perf-norm:sp_reserve_write_off 9609 9609 0 0.00 0 8162 22 0 0
t$perf-norm:sp_supplier_invoice 5412 5412 0 0.00 0 8095 30 0 0
t$perf-norm:sp_supplier_order 5325 5325 0 0.00 0 4722 24 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 288 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 1496 26 0 0
t$perf-norm:srv_make_invnt_saldo 466 466 0 0.00 0 6051 25 0 0
t$perf-norm:srv_make_money_saldo 863 863 0 0.00 0 6060 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 117 43 0 0
v_add_invoice_to_stock 10032 9702 330 3.28 0 5301 26 0 330
v_all_customers 35662 35662 0 0.00 0 99 3 0 0
v_all_suppliers 29738 29738 0 0.00 0 176 3 0 0
v_all_wares 38510 38510 0 0.00 0 213 3 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 1465 14 0 0
v_cancel_client_order 3350 3350 0 0.00 0 4207 12 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 4179 14 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 4241 13 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 5454 21 0 0
v_cancel_supplier_invoice 846 818 28 3.30 0 1129 21 0 28
v_cancel_supplier_order 830 830 0 0.00 0 401 10 0 0
v_cancel_write_off 1968 1968 0 0.00 0 8799 22 0 0
v_max_id_clo_ord 5324 5324 0 0.00 0 330 4 0 0
v_max_id_clo_res 951 951 0 0.00 0 869 9 0 0
v_max_id_ord_sup 5407 5407 0 0.00 0 4480 7 0 0
v_max_non_paid_invoice 1546 1546 0 0.00 0 519 7 0 0
v_max_non_paid_realizn 3460 3460 0 0.00 0 1265 6 0 0
v_min_id_clo_ord 5324 5324 0 0.00 0 1922 6 0 0
v_min_id_clo_res 951 951 0 0.00 0 7224 19 0 0
v_min_id_ord_sup 5407 5407 0 0.00 0 6623 12 0 0
v_min_non_paid_invoice 5684 5684 0 0.00 0 9502 19 0 0
v_min_non_paid_realizn 6775 6775 0 0.00 0 4334 14 0 0
v_our_firm 9478 9478 0 0.00 0 83 3 0 0
v_reserve_write_off 19218 19218 0 0.00 0 9303 11 0 0
x_lock_depdocs_on_canc_invoice 10617 10617 0 0.00 0 31364 134 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 5428 334 0 0
x_make_qstorno_1000_1200 5325 5325 0 0.00 2 41329 1145 0 0
x_make_qstorno_1200_2000 5412 5412 0 0.00 3 77854 3048 0 0
x_make_qstorno_2100_3300 452896 452896 0 0.00 0 142715 202 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-09-2022 10:38:50 to 25-09-2022 10:38:54.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:25 37251 8192000000 9711315104 10009481216 789865888 8.794 100 93 186 0 821858704 8824960 23167856
08:10:08 15634 8192000000 9749960576 10058534912 827885360 9.178 100 86 172 0 861039312 12505824 20648128
08:11:26 12085 8192000000 9784878688 10103377920 856100720 9.461 100 82 162 0 892715888 17387520 19227648
08:12:42 10017 8192000000 9855710864 10190454784 911454816 10.012 100 82 164 0 968241968 38064496 18722656
08:13:56 2317 8192000000 9905414416 10246537216 958758704 10.477 100 86 168 1 1022137056 45699056 17679296
08:15:02 2382 8192000000 9962234000 10312826880 991810032 10.799 100 94 185 2 1077333200 66984448 18538720
08:16:07 1791 8192000000 9989992752 10352615424 1023093552 11.102 100 90 175 2 1114169536 73794832 17281152
08:17:11 993 8192000000 10000014864 10370797568 1048687216 11.348 100 90 166 0 1123319968 57577760 17054992
08:18:13 1028 8192000000 10043212400 10419417088 1068215440 11.535 100 98 187 1 1158633344 71948688 18469216
08:19:15 904 8192000000 10046765200 10430627840 1077593840 11.625 100 92 179 1 1163926224 68214960 18117424
08:20:18 1255 8192000000 10049901280 10436096000 1081167696 11.659 100 85 165 2 1172972464 75410864 16393904
08:21:20 2624 8192000000 10035173968 10424750080 1088380736 11.727 100 79 154 2 1163098192 59397856 15319600
08:22:23 7911 8192000000 10061877680 10445447168 1087087952 11.715 100 81 153 0 1184030112 82418608 14523552
08:23:32 1196 8192000000 10070307968 10458243072 1090509904 11.748 100 88 173 4 1190931040 82749440 17671696
08:24:35 1201 8192000000 10071721232 10464440320 1097386256 11.813 100 78 147 1 1191454640 79012928 15055456
08:25:38 1108 8192000000 10054318576 10448211968 1096912400 11.808 100 90 170 1 1178183120 63278096 17992624
08:26:40 1121 8192000000 10045009008 10439225344 1102793376 11.864 100 83 157 1 1164341712 45465872 16082464
08:27:42 1033 8192000000 10060043488 10453762048 1096868896 11.808 100 82 155 1 1181620224 68655968 16095360
08:28:43 1062 8192000000 10056967888 10449252352 1088784016 11.731 100 89 173 2 1181529072 74185040 18560016
08:29:46 1112 8192000000 10041886064 10428960768 1079159984 11.639 100 92 177 2 1163050864 66080048 17810832
08:30:48 1141 8192000000 10040498160 10422218752 1075235168 11.602 100 98 190 0 1158831408 63948912 19647328
08:31:49 1818 8192000000 10023516736 10416369664 1071237376 11.564 100 84 159 2 1151419552 62911104 17271072
08:32:52 1548 8192000000 10023196320 10405228544 1054648144 11.405 100 74 145 0 1141874576 71655792 15570640
08:33:55 3720 8192000000 10023283456 10411003904 1051465616 11.375 100 62 126 0 1150021408 86004592 12551200
08:35:00 2012 8192000000 10007910080 10387464192 1044372928 11.307 100 89 168 2 1131758512 70629872 16755712
08:36:03 1128 8192000000 9982507568 10359922688 1039275376 11.258 100 93 167 3 1106424352 49532368 17616608
08:37:05 2233 8192000000 9990612976 10372284416 1046942960 11.331 100 66 123 2 1107877024 47613824 13320240
08:38:13 14710 8192000000 9992987408 10384330752 1058705184 11.444 100 59 113 0 1115486080 45152480 11628416
08:39:35 3463 8192000000 10022603104 10403663872 1068590448 11.539 99 75 142 0 1140058176 57236944 14230784
08:40:48 1929 8192000000 10058040864 10444247040 1087216320 11.716 100 84 150 3 1179782864 77763296 14803248
08:41:53 856 8192000000 10101651536 10486386688 1105614512 11.891 100 94 175 2 1220906368 94206256 21085600
08:42:59 2319 8192000000 10137544480 10528759808 1106935824 11.903 99 85 167 2 1256234384 132415840 16882720
08:44:03 1013 8192000000 10182063984 10574364672 1126725136 12.090 100 94 186 1 1300688016 151872272 22090608
08:45:07 1310 8192000000 10199845168 10593423360 1132038336 12.141 100 90 169 2 1315498464 163979552 19480576
08:46:12 2230 8192000000 10241563104 10640080896 1138242688 12.199 100 76 148 0 1352009344 197268592 16498064
08:47:19 1064 8192000000 10282222608 10675068928 1145587088 12.268 100 93 178 2 1397871392 232372416 19911888
08:48:23 1407 8192000000 10289982336 10688344064 1158790304 12.392 100 87 162 0 1402604848 226280896 17533648
08:49:28 1883 8192000000 10327554896 10748002304 1168819248 12.486 100 85 164 0 1438128144 249901168 19407728
08:50:35 3716 8192000000 10333549200 10741481472 1177900864 12.571 100 88 175 0 1448872496 250815824 20155808
08:51:43 818 8192000000 10372085488 10775089152 1179883168 12.589 100 94 186 0 1478540464 276198912 22458384
08:52:47 1280 8192000000 10414322752 10838519808 1192229296 12.704 100 95 174 2 1520645680 307551808 20864576
08:53:52 1700 8192000000 10441521824 10868707328 1197903376 12.757 100 92 174 1 1546954704 327520848 21530480
08:54:56 2071 8192000000 10478524704 10892783616 1192347424 12.705 100 78 154 0 1589371920 378068176 18956320
08:56:06 1156 8192000000 10486012368 10913181696 1196967920 12.748 100 96 185 4 1588990368 369264560 22757888
08:57:12 784 8192000000 10477702720 10938597376 1183921120 12.627 100 96 189 3 1584171664 376944192 23306352
08:58:14 11418 8192000000 10376563280 10825469952 1172900288 12.524 100 38 72 1 1499830160 316869552 10060320
08:59:29 8475 8192000000 10390769536 10851491840 1163293600 12.434 100 31 62 0 1509150240 337184224 8672416
09:00:48 39014 8192000000 10361322048 10846121984 1159193376 12.396 100 26 50 0 1471761088 305787680 6780032
09:02:52 16398 8192000000 10256153904 10731491328 1154590048 12.353 100 30 61 0 1358034528 196452096 6992384
09:04:13 13903 8192000000 10270456096 10712104960 1163098912 12.432 100 34 66 0 1378346096 207173888 8073296
09:05:31 93386 8192000000 10124079568 10608001024 1091330464 11.755 95 19 36 0 1198976480 102699152 4946864
09:08:06 115783 8192000000 10129928048 10587672576 1137181664 12.189 99 25 50 0 1200147744 57057728 5908352
09:11:13 83544 8192000000 10175538144 10642501632 1124160400 12.066 98 21 39 0 1256924544 127874640 4889504
09:13:44 29420 8192000000 10100798048 10547769344 1141163296 12.226 100 21 40 0 1210235104 63948368 5123440
09:15:24 184639 8192000000 10107012896 10574495744 1051262816 11.373 91 26 50 1 1154979168 97986976 5729376
09:20:21 57332 8192000000 10123468288 10598617088 1142437328 12.238 99 36 70 0 1220080592 70031632 7611632
09:23:18 71652 8192000000 10130431376 10589876224 1141887600 12.233 99 18 36 0 1208852832 63392224 3573008
09:25:54 56185 8192000000 10111384240 10567647232 1124964800 12.074 98 33 61 0 1205944752 73631632 7348320
09:27:54 63832 8192000000 10085027872 10580316160 1088652656 11.730 96 28 54 1 1167914496 73226416 6035424
09:29:58 45254 8192000000 10163239520 10604081152 1125101760 12.075 99 51 99 2 1235709616 99079520 11528336
09:31:44 53751 8192000000 10110159120 10600841216 1139367664 12.210 100 9 18 0 1209357392 67905136 2084592
09:33:38 69598 8192000000 10090840544 10570199040 1117989056 12.008 98 12 24 0 1194233920 73872800 2372064
09:35:49 94528 8192000000 10062565392 10552020992 1105682992 11.892 97 7 14 0 1143915296 36458704 1773600
09:38:35 16875 8192000000 10060650832 10495365120 1136029008 12.178 100 54 93 3 1162104688 16045120 10030560
09:39:52 47073 8192000000 10077716176 10533220352 1107884640 11.912 98 21 42 0 1198796000 86581792 4329568
09:41:41 46779 8192000000 10032110208 10473222144 1113672624 11.967 99 9 16 0 1153643616 38177344 1793648
09:44:09 63201 8192000000 10095221328 10584461312 1139926896 12.215 100 15 30 0 1208892320 65607856 3357568
09:46:13 51849 8192000000 10093931888 10539532288 1128585184 12.108 99 22 44 0 1192147936 59016048 4546704
09:48:25 47999 8192000000 10055951712 10493702144 1078272992 11.631 94 14 26 0 1111824016 30986432 2564592
09:50:17 61225 8192000000 10123971840 10579857408 1139847616 12.214 100 30 60 0 1217401808 71348368 6205824
09:52:19 55433 8192000000 10118225968 10564886528 1110811936 11.940 97 37 74 0 1194287456 75288784 8186736
09:54:16 31771 8192000000 10114074704 10534604800 1129749680 12.119 99 54 108 0 1192367200 51295680 11321840
09:55:50 26655 8192000000 10167531776 10603929600 1153258912 12.340 100 35 70 0 1271960464 111073024 7628528
09:57:17 65677 8192000000 10232389776 10712465408 1155912480 12.365 100 17 34 0 1329369536 169330688 4126368
09:59:23 74062 8192000000 10107264688 10662780928 1130838464 12.129 99 5 10 0 1206891408 75174992 877952
10:01:51 46304 8192000000 10077525888 10549374976 1139944960 12.215 100 28 56 0 1191500880 45852992 5702928
10:03:43 42235 8192000000 10060116096 10521378816 1120684800 12.033 98 10 20 0 1158498464 35930656 1883008
10:05:42 57478 8192000000 10076903824 10542641152 1138841408 12.205 100 18 36 1 1173277808 30652576 3783824
10:07:40 53769 8192000000 10054043792 10510692352 1127590208 12.099 99 26 51 0 1173648720 40563408 5495104
10:09:34 61938 8192000000 10076615568 10535784448 1124418752 12.069 98 17 34 0 1175798048 47621152 3758144
10:11:38 77319 8192000000 10101259584 10584932352 1133145120 12.151 99 20 40 0 1206287744 68201120 4941504
10:13:55 13305 8192000000 10056735424 10495279104 1111490416 11.947 98 41 78 0 1145718928 25863232 8365280
10:15:10 45430 8192000000 10064288896 10536468480 1119587280 12.023 99 34 67 0 1181636096 54979712 7069104
10:16:59 26189 8192000000 10133277744 10572591104 1138546432 12.202 100 48 96 0 1220172880 72242736 9383712
10:18:25 7959 8192000000 10123459840 10552696832 1132392592 12.144 100 52 99 0 1201354912 59498688 9463632
10:19:45 6515 8192000000 10111300992 10537865216 1118766144 12.015 99 51 102 0 1191991008 62791568 10433296
10:21:58 4509 8192000000 10101529760 10521747456 1122467376 12.050 100 63 120 4 1189206400 54793456 11945568
10:23:03 23283 8192000000 10099102960 10545897472 1114331264 11.973 99 42 84 1 1196324864 74001008 7992592
10:24:27 81885 8192000000 10086939488 10566111232 1074691552 11.597 95 30 60 1 1146226832 65754704 5780576
10:26:52 91268 8192000000 10066928640 10554875904 1102335072 11.860 98 22 44 0 1158710352 51959040 4416240
10:29:33 44917 8192000000 10042533584 10491129856 1090934240 11.752 97 14 28 0 1121483824 27614176 2935408
10:31:36 95747 8192000000 10084463472 10611052544 1128433056 12.107 100 12 24 0 1222178928 90803520 2942352
10:34:15 41162 8192000000 10077534304 10531475456 1114177776 11.972 98 24 47 0 1146451632 27419520 4854336
10:36:00 48149 8192000000 10115165648 10570514432 1139263312 12.209 100 35 69 0 1199020800 52648336 7109152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-09-2022 10:38:56 to 25-09-2022 10:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 165 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-09-2022 10:39:12 to 25-09-2022 10:39:14.

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          230522
MON$OLDEST_ACTIVE               230523
MON$OLDEST_SNAPSHOT             230523
MON$NEXT_TRANSACTION            230523
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                       2019840
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

25.09.22 10:39:16. 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-09-25 10:39:18 No severe PSQL-related problems occured
25.09.22 10:39:19. End of report.