Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.07.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 15848652 kB
MemAvailable 31072328 kB
Buffers 20 kB
Cached 15825952 kB
SwapCached 2484 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-17 04:37:18
DTS_END                         2022-07-17 04:37:18.7780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53100
TRN_ID                          219386
ATT_ID                          615
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: 8725.50 23213 1047060
customer order: creation 168.92 483 20270
customer order: refuse 13.94 396 1673
order to supplier: creation 45.66 1271 5479
order to supplier: removal 3.18 166966 381
invoice (draft): creation 45.23 3430 5427
invoice (draft): removal 46.94 994 5633
invoice accept: apply 41.65 28111 4998
invoice accept: cancel 42.68 1220 5121
customer reserve: creation 3872.59 270 464711
customer reserve: removal 4158.20 101 498984
realization accept: apply 82.27 269 9872
realization accept: cancel 75.82 161 9098
payment from customer: creation 58.28 181 6993
payment from customer: removal 5.68 114 681
payment to supplier: creation 47.80 212 5736
payment to supplier: removal 3.43 118 412
service: total inventory turnovers 4.26 3765 511
service: total monetary turnovers 8.04 315 965
service: aggregate perf. data 0.93 15723 111
service: refresh index statistics 0.03 240161 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-07-2022 04:39:21 to 17-07-2022 04:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8627
TEST_TIME 2 6876
TEST_TIME 3 6487
TEST_TIME 4 6814
TEST_TIME 5 7025
TEST_TIME 6 7436
TEST_TIME 7 7415
TEST_TIME 8 7247
TEST_TIME 9 7202
TEST_TIME 10 7125
TEST_TIME 11 7112
TEST_TIME 12 7185
TEST_TIME 13 7139
TEST_TIME 14 7182
TEST_TIME 15 7186
TEST_TIME 16 7627
TEST_TIME 17 8059
TEST_TIME 18 8157
TEST_TIME 19 8085
TEST_TIME 20 8136
TEST_TIME 21 8493
TEST_TIME 22 8983
TEST_TIME 23 8840
TEST_TIME 24 8792
TEST_TIME 25 8967
TEST_TIME 26 9080
TEST_TIME 27 9227
TEST_TIME 28 9483
TEST_TIME 29 9509
TEST_TIME 30 9797
TEST_TIME 31 10154
TEST_TIME 32 10412
TEST_TIME 33 10378
TEST_TIME 34 10900
TEST_TIME 35 10627
TEST_TIME 36 10382
TEST_TIME 37 10395
TEST_TIME 38 10281
TEST_TIME 39 10158
TEST_TIME 40 10191
TEST_TIME 41 10165
TEST_TIME 42 9981
TEST_TIME 43 9935
TEST_TIME 44 9834
TEST_TIME 45 9782
TEST_TIME 46 9677
TEST_TIME 47 9621
TEST_TIME 48 9586
TEST_TIME 49 9505
TEST_TIME 50 9429
TEST_TIME 51 9331
TEST_TIME 52 9302
TEST_TIME 53 9267
TEST_TIME 54 9306
TEST_TIME 55 9196
TEST_TIME 56 9064
TEST_TIME 57 9099
TEST_TIME 58 9022
TEST_TIME 59 8991
TEST_TIME 60 8915
TEST_TIME 61 8803
TEST_TIME 62 8908
TEST_TIME 63 8862
TEST_TIME 64 8882
TEST_TIME 65 8911
TEST_TIME 66 8925
TEST_TIME 67 8930
TEST_TIME 68 8891
TEST_TIME 69 8895
TEST_TIME 70 8902
TEST_TIME 71 8874
TEST_TIME 72 8866
TEST_TIME 73 8898
TEST_TIME 74 8881
TEST_TIME 75 8969
TEST_TIME 76 8949
TEST_TIME 77 8899
TEST_TIME 78 8856
TEST_TIME 79 8853
TEST_TIME 80 8818
TEST_TIME 81 8757
TEST_TIME 82 8734
TEST_TIME 83 8704
TEST_TIME 84 8675
TEST_TIME 85 8655
TEST_TIME 86 8618
TEST_TIME 87 8647
TEST_TIME 88 8635
TEST_TIME 89 8636
TEST_TIME 90 8671
TEST_TIME 91 8726
TEST_TIME 92 8714
TEST_TIME 93 8727
TEST_TIME 94 8730
TEST_TIME 95 8724
TEST_TIME 96 8745
TEST_TIME 97 8773
TEST_TIME 98 8756
TEST_TIME 99 8813
TEST_TIME 100 8745
TEST_TIME 101 8709
TEST_TIME 102 8714
TEST_TIME 103 8664
TEST_TIME 104 8608
TEST_TIME 105 8585
TEST_TIME 106 8581
TEST_TIME 107 8606
TEST_TIME 108 8585
TEST_TIME 109 8588
TEST_TIME 110 8593
TEST_TIME 111 8567
TEST_TIME 112 8554
TEST_TIME 113 8550
TEST_TIME 114 8552
TEST_TIME 115 8595
TEST_TIME 116 8583
TEST_TIME 117 8625
TEST_TIME 118 8754
TEST_TIME 119 8811
TEST_TIME 120 8754

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-07-2022 04:39:24 to 17-07-2022 04:39:27.

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 1472933 1472933 0 0.00 0 23516 14 0 0
doc_list_biud 1472917 1472917 0 0.00 0 23132 40 0 0
sp_add_invoice_to_stock 5080 4998 82 1.61 45 229788 28112 0 82
sp_cancel_adding_invoice 5121 5121 0 0.00 8 74404 1221 0 0
sp_cancel_client_order 1673 1673 0 0.00 5 12194 397 0 0
sp_cancel_customer_reserve 498984 498984 0 0.00 1 23176 101 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 4 5712 114 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 4 4455 119 0 0
sp_cancel_supplier_invoice 5641 5633 8 0.14 2 19115 995 0 8
sp_cancel_supplier_order 381 381 0 0.00 18 1241624 166966 0 0
sp_cancel_write_off 9098 9098 0 0.00 2 9704 161 0 0
sp_client_order 20270 20270 0 0.00 5 31602 484 0 0
sp_customer_reserve 464711 464711 0 0.00 0 48623 270 0 0
sp_fill_shopping_cart 31167 31167 0 0.00 1 43321 88 0 0
sp_fill_shopping_cart_clo_res 464678 464678 0 0.00 0 16235 21 0 0
sp_get_clo_for_invoice 4956 4956 0 0.00 0 36024 357 0 0
sp_kill_cost_storno 15312 15312 0 0.00 0 8744 71 0 0
sp_kill_qstorno_ret_qs2qd 1013080 1013080 0 0.00 0 22573 44 0 0
sp_kill_qty_storno 535713 535713 0 0.00 0 23128 102 0 0
sp_lock_selected_doc 20364 20364 0 0.00 0 5942 6 0 0
sp_make_cost_storno 27556 27556 0 0.00 0 23514 80 0 0
sp_multiply_rows_for_pdistr 27553 27553 0 0.00 0 9283 37 0 0
sp_multiply_rows_for_qdistr 20269 20269 0 0.00 0 23439 177 0 0
sp_pay_from_customer 6993 6993 0 0.00 5 18965 182 0 0
sp_pay_to_supplier 5736 5736 0 0.00 7 23523 212 0 0
sp_payment_common 12727 12727 0 0.00 2 18841 55 0 0
sp_qd_handle_on_cancel_clo 1673 1673 0 0.00 0 12182 321 0 0
sp_qd_handle_on_invoice_upd_sts 10072 10072 0 0.00 0 22387 519 0 0
sp_qd_handle_on_reserve_upd_sts 9872 9872 0 0.00 0 12748 59 0 0
sp_reserve_write_off 9872 9872 0 0.00 8 20274 270 0 0
sp_supplier_invoice 5427 5427 0 0.00 9 92742 3430 0 0
sp_supplier_order 5479 5479 0 0.00 8 36884 1271 0 0
srv_aggregate_perf_data 112 111 1 0.89 2406 240696 15723 0 1
srv_fill_mon_cache_memory 72 72 0 0.00 740 106906 32474 0 0
srv_find_qd_qs_mism 997417 997417 0 0.00 0 17461 12 0 0
srv_make_invnt_saldo 511 511 0 0.00 64 71740 3766 0 0
srv_make_money_saldo 965 965 0 0.00 1 8491 315 0 0
srv_recalc_idx_stat 4 4 0 0.00 84290 404812 240162 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 8 8 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5001 5001 0 0.00 0 20767 878 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 1 8676 383 0 0
t$perf-norm:sp_cancel_client_order 1673 1673 0 0.00 0 3883 25 0 0
t$perf-norm:sp_cancel_customer_reserve 608 608 0 0.00 0 3615 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 311 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 163 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 777 39 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 94840 7520 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 1363 16 0 0
t$perf-norm:sp_client_order 20270 20270 0 0.00 0 10181 35 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 7713 27 0 0
t$perf-norm:sp_pay_from_customer 6993 6993 0 0.00 0 11348 18 0 0
t$perf-norm:sp_pay_to_supplier 5737 5737 0 0.00 0 12720 26 0 0
t$perf-norm:sp_reserve_write_off 9872 9872 0 0.00 0 11110 27 0 0
t$perf-norm:sp_supplier_invoice 5427 5427 0 0.00 0 13863 35 0 0
t$perf-norm:sp_supplier_order 5479 5479 0 0.00 0 8431 31 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 628 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 1068 28 0 0
t$perf-norm:srv_make_invnt_saldo 511 511 0 0.00 0 526 8 0 0
t$perf-norm:srv_make_money_saldo 965 965 0 0.00 0 2364 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 15 203 76 0 0
v_add_invoice_to_stock 10056 9892 164 1.63 0 10162 25 0 164
v_all_customers 37084 37084 0 0.00 0 148 4 0 0
v_all_suppliers 30333 30333 0 0.00 0 148 4 0 0
v_all_wares 40536 40536 0 0.00 0 150 4 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 10073 28 0 0
v_cancel_client_order 3346 3346 0 0.00 0 5265 14 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 5704 17 0 0
v_cancel_customer_reserve 1216 1216 0 0.00 1 7407 17 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 632 11 0 0
v_cancel_supplier_invoice 768 752 16 2.08 0 1138 23 0 16
v_cancel_supplier_order 762 762 0 0.00 0 4415 19 0 0
v_cancel_write_off 2000 2000 0 0.00 0 7523 27 0 0
v_max_id_clo_ord 5478 5478 0 0.00 0 407 5 0 0
v_max_id_clo_res 973 973 0 0.00 0 3228 19 0 0
v_max_id_ord_sup 5421 5421 0 0.00 0 5470 9 0 0
v_max_non_paid_invoice 1468 1468 0 0.00 0 542 8 0 0
v_max_non_paid_realizn 3601 3601 0 0.00 0 2095 7 0 0
v_min_id_clo_ord 5478 5478 0 0.00 0 1881 7 0 0
v_min_id_clo_res 973 973 0 0.00 0 1049 11 0 0
v_min_id_ord_sup 5421 5421 0 0.00 0 7504 10 0 0
v_min_non_paid_invoice 5736 5736 0 0.00 0 17044 29 0 0
v_min_non_paid_realizn 6991 6991 0 0.00 0 18828 29 0 0
v_our_firm 10232 10232 0 0.00 0 60 4 0 0
v_reserve_write_off 19744 19744 0 0.00 0 10521 12 0 0
x_lock_depdocs_on_canc_invoice 10378 10378 0 0.00 0 22321 113 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 8069 379 0 0
x_make_qstorno_1000_1200 5479 5479 0 0.00 3 36791 1119 0 0
x_make_qstorno_1200_2000 5427 5427 0 0.00 4 90209 3154 0 0
x_make_qstorno_2100_3300 464709 464709 0 0.00 0 48597 237 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 17-07-2022 04:39:28 to 17-07-2022 04:39:33.

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:22 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:22 28369 8192000000 9727063392 10025017344 795883424 8.855 100 82 164 0 822772160 7439968 19448768
02:09:58 16339 8192000000 9764197520 10071482368 840100736 9.301 100 87 174 0 872452928 11931328 20420864
02:11:23 8567 8192000000 9803473776 10117971968 867231520 9.572 100 88 173 0 909185312 22066208 19887584
02:12:36 8722 8192000000 9851982304 10184994816 910359808 10.001 100 77 153 0 961652736 34831952 16460976
02:13:49 5123 8192000000 9907416512 10248495104 953212864 10.423 100 83 164 0 1018380832 47721776 17446192
02:14:59 4034 8192000000 9958745664 10311118848 984347168 10.727 100 90 177 1 1074112288 71573440 18191680
02:16:06 1252 8192000000 9979963488 10339409920 1009269120 10.968 100 93 176 0 1100914000 74358304 17286576
02:17:10 3203 8192000000 9996295568 10363658240 1024405056 11.115 100 78 151 1 1121560640 82927840 14227744
02:18:14 1514 8192000000 10032778896 10409992192 1040945504 11.274 100 96 190 2 1154134944 93917248 19272192
02:19:19 780 8192000000 10027097968 10398408704 1051362800 11.374 100 96 181 0 1145251392 75648352 18240240
02:20:21 1345 8192000000 10069465296 10445819904 1061994336 11.476 100 98 192 0 1191237968 110442096 18801536
02:21:25 926 8192000000 10052451664 10434297856 1071440560 11.566 100 89 169 1 1173694448 85742080 16511808
02:22:28 994 8192000000 10049779504 10439643136 1077714896 11.626 100 89 170 2 1177505680 82685088 17105696
02:23:30 970 8192000000 10028795168 10413895680 1077350272 11.622 100 97 179 1 1154260720 57790416 19120032
02:24:33 1056 8192000000 10056933376 10441080832 1085783696 11.703 100 98 183 0 1179758640 75151264 18823680
02:25:35 1189 8192000000 10034927232 10420027392 1090061120 11.743 100 97 189 1 1158860992 48827744 19972128
02:26:37 1117 8192000000 10062612624 10452484096 1095875104 11.798 100 93 171 1 1183724432 69749056 18100272
02:27:39 1091 8192000000 10062129424 10455425024 1097722208 11.816 100 92 169 2 1184652032 69934384 16995440
02:28:41 2298 8192000000 10038507840 10432851968 1087818864 11.722 100 79 153 1 1164686656 61460736 15407056
02:29:44 1159 8192000000 10054286656 10438422528 1089414224 11.737 100 95 177 0 1174455760 65952624 19088912
02:30:47 1303 8192000000 10012276976 10387599360 1061721728 11.473 98 97 188 3 1128393904 48036368 18635808
02:31:49 1060 8192000000 10036702992 10414227456 1065810560 11.512 100 95 183 0 1156035456 71635664 18589232
02:32:51 981 8192000000 9999129136 10372665344 1023370576 11.105 98 92 174 0 1120816256 79346400 18099280
02:33:53 882 8192000000 9985178784 10362097664 1042899200 11.293 100 91 175 3 1108474912 46414384 19161328
02:34:55 1503 8192000000 9996037184 10368274432 1046259328 11.325 100 89 169 4 1119108080 56155136 16693616
02:35:58 883 8192000000 9996179264 10375839744 1049429808 11.355 100 93 164 1 1118368208 52594160 16344240
02:37:00 5110 8192000000 10000484560 10381426688 1038215248 11.248 99 70 124 6 1131503712 80132000 13156464
02:38:10 3879 8192000000 10002485744 10377568256 1059205920 11.449 100 75 127 3 1114761216 43519088 12036208
02:39:21 1165 8192000000 10050234752 10427490304 1075690512 11.606 100 88 170 2 1159630080 67491280 16448288
02:40:29 5853 8192000000 10071699664 10466455552 1091018720 11.752 100 72 143 0 1195847632 89454928 15373984
02:41:43 2212 8192000000 10079480656 10465579008 1103770752 11.873 100 91 170 3 1201402992 79954432 17677808
02:42:51 2993 8192000000 10132560480 10528337920 1112100000 11.952 100 78 154 2 1259356480 130499360 16757120
02:43:56 1138 8192000000 10146004704 10536771584 1132754336 12.147 100 94 185 0 1263223264 111232976 19235952
02:45:01 938 8192000000 10179672720 10576396288 1143311216 12.247 100 88 170 1 1291724672 130131696 18281760
02:46:07 1761 8192000000 10205618672 10602680320 1151111728 12.320 100 85 161 1 1313581616 144773472 17696416
02:47:15 725 8192000000 10220304496 10612965376 1145684592 12.269 99 92 169 0 1330054000 165322512 19046896
02:48:19 1013 8192000000 10259500816 10659332096 1156322928 12.369 100 92 183 1 1376032464 199008512 20701024
02:49:25 915 8192000000 10293667424 10703568896 1167700240 12.475 100 97 188 2 1402721792 213112160 21909392
02:50:30 968 8192000000 10285980816 10695671808 1163085360 12.432 100 79 154 0 1393333088 212758256 17489472
02:51:36 1042 8192000000 10331656496 10730536960 1170419792 12.501 100 97 186 1 1441604768 249626144 21558832
02:52:39 1948 8192000000 10381088880 10789814272 1173937248 12.534 100 98 194 0 1483384928 286746432 22701248
02:53:46 886 8192000000 10375479776 10823184384 1186530736 12.651 100 97 189 0 1484836800 275795504 22510560
02:54:49 801 8192000000 10362245648 10766553088 1177451296 12.566 100 97 189 1 1469472592 271249552 20771744
02:55:55 1069 8192000000 10359189312 10776109056 1173273488 12.527 100 94 179 1 1469459040 274801072 21384480
02:57:00 874 8192000000 10421869792 10837487616 1185222464 12.639 100 98 194 3 1528273648 320567600 22483584
02:58:04 820 8192000000 10422075328 10875305984 1186579664 12.652 100 91 181 1 1529711424 321197360 21934400
02:59:09 3525 8192000000 10407768320 10865606656 1191071776 12.693 100 77 151 0 1514684832 305290128 18322928
03:00:14 21643 8192000000 10403158256 10849460224 1179076496 12.582 99 37 70 1 1482818736 294864080 8878160
03:01:54 2626 8192000000 10408840880 10866987008 1179057280 12.581 100 46 88 1 1515234976 325156768 11020928
03:03:00 14507 8192000000 10397693360 10853715968 1163619008 12.437 100 46 92 1 1513991120 338617760 11754352
03:04:17 13468 8192000000 10384419872 10829811712 1136812400 12.186 98 43 84 0 1478760944 331508560 10439984
03:05:33 17013 8192000000 10401093600 10882486272 1161594864 12.418 100 33 65 1 1499292160 329524048 8173248
03:07:10 12893 8192000000 10413224176 10885345280 1158230848 12.387 100 35 68 0 1507836416 340847120 8758448
03:08:23 26848 8192000000 10313843744 10802647040 1164074704 12.441 100 30 60 0 1404923136 233382912 7465520
03:10:03 15894 8192000000 10283988416 10722648064 1146436944 12.276 99 41 76 0 1365358704 210364816 8556944
03:11:20 17518 8192000000 10160708672 10625163264 1155866000 12.365 100 38 74 1 1282242224 118167616 8208608
03:12:38 67696 8192000000 10196665104 10626605056 1145498144 12.267 99 71 136 0 1276605024 115904848 15202032
03:14:51 52154 8192000000 10156785232 10631094272 1132794112 12.148 99 23 45 0 1275854816 138198256 4862448
03:17:08 61320 8192000000 10104923424 10577813504 1136344528 12.181 100 17 34 0 1243718112 103415920 3957664
03:19:15 79114 8192000000 10065486384 10521341952 1109881424 11.931 97 31 62 1 1163000928 46656848 6462656
03:21:35 67109 8192000000 10092646400 10547814400 1106124720 11.896 97 36 62 1 1174581024 61969312 6486992
03:24:14 75144 8192000000 10072886848 10538455040 1089801056 11.741 96 25 46 1 1153913056 59757184 4354816
03:26:36 54803 8192000000 10119475104 10578194432 1124462848 12.069 99 18 36 0 1217151424 88517280 4171296
03:29:17 72805 8192000000 10127929248 10646839296 1134469296 12.163 100 14 27 0 1245899280 108207824 3222160
03:31:39 64880 8192000000 10086387680 10563153920 1124513472 12.070 99 42 82 0 1166808880 32467776 9827632
03:33:54 5131 8192000000 10092188624 10535723008 1127704288 12.100 100 63 117 1 1189299616 48800368 12794960
03:35:04 63211 8192000000 10075878256 10541166592 1090621328 11.749 96 18 36 0 1141416016 47124896 3669792
03:37:15 106906 8192000000 10103271552 10597785600 1142292416 12.237 100 14 28 0 1219935488 74850768 2792304
03:40:38 56875 8192000000 10093583824 10567647232 1123217024 12.057 98 20 40 0 1164573824 37774720 3582080
03:42:35 48777 8192000000 10066923488 10510155776 1091940928 11.761 96 23 46 0 1125144240 28915424 4287888
03:44:25 34967 8192000000 10071886560 10531639296 1127439392 12.097 99 23 46 0 1180259248 48163824 4656032
03:46:05 15111 8192000000 10084820528 10535153664 1135463664 12.173 100 61 120 0 1200557520 52663008 12430848
03:47:21 30270 8192000000 10105231376 10551377920 1113462704 11.965 98 47 94 0 1193602576 70547168 9592704
03:48:53 20022 8192000000 10109085088 10541981696 1133760512 12.157 100 42 84 0 1198726720 56634048 8332160
03:50:14 20294 8192000000 10112238944 10561740800 1130692624 12.128 99 48 96 0 1207552720 67431632 9428464
03:51:43 28846 8192000000 10136452400 10571243520 1127667008 12.099 99 37 74 0 1225982032 90524192 7790832
03:53:43 59449 8192000000 10087100768 10544599040 1117060624 11.999 99 40 80 0 1183532192 57755968 8715600
03:56:02 73517 8192000000 10079492000 10556272640 1101854880 11.855 97 13 25 0 1177568400 72987136 2726384
03:58:16 63578 8192000000 10078806208 10534981632 1108872672 11.922 98 12 24 0 1160954848 49521360 2560816
04:00:19 45252 8192000000 10060838160 10539466752 1095818336 11.798 97 13 26 0 1140981456 41937472 3225648
04:02:18 53903 8192000000 10061846080 10509119488 1070358672 11.556 96 19 38 0 1119479344 45139120 3981552
04:04:14 72859 8192000000 10070260880 10516643840 1103615408 11.872 98 13 26 0 1165746032 59135168 2995456
04:06:28 64293 8192000000 10119578976 10572296192 1141951408 12.234 100 28 56 0 1231965456 84006576 6007472
04:08:33 77398 8192000000 10098536048 10551459840 1086943440 11.714 95 20 40 1 1131603408 40704784 3955184
04:10:56 23739 8192000000 10096405952 10530344960 1134190256 12.161 100 43 86 0 1203973840 61551488 8232096
04:12:23 9075 8192000000 10103595280 10517823488 1126499552 12.088 100 77 151 3 1202311744 60254448 15557744
04:13:34 36842 8192000000 10117021296 10590175232 1121783216 12.044 99 45 89 0 1221708304 91016928 8908160
04:15:50 21147 8192000000 10123750688 10570465280 1123699696 12.062 99 50 100 0 1216073632 81619920 10754016
04:17:12 79488 8192000000 10071410320 10500333568 1126049440 12.084 99 51 97 0 1179383024 43388496 9945088
04:19:33 46496 8192000000 10054921440 10521411584 1121860288 12.045 99 15 31 0 1179927472 54911552 3155632
04:22:07 63461 8192000000 10082232896 10538225664 1115591024 11.985 98 25 48 0 1194922848 73863184 5468640
04:24:17 63190 8192000000 10072844272 10567475200 1135178944 12.170 100 13 25 0 1191313232 53444000 2690288
04:26:23 39868 8192000000 10083747136 10517626880 1099797312 11.836 96 25 47 0 1153260096 48263872 5198912
04:28:06 26865 8192000000 10101548256 10536042496 1121653744 12.043 99 36 70 2 1192511232 62851024 8006464
04:29:34 25304 8192000000 10174129904 10603778048 1146674912 12.278 100 50 99 0 1272555088 115028816 10851360
04:31:00 44040 8192000000 10208040928 10640961536 1122318144 12.049 98 43 86 0 1294976704 163326704 9331856
04:32:46 44542 8192000000 10193620096 10622033920 1124525568 12.070 98 43 84 1 1281265728 147268560 9471600
04:34:37 64022 8192000000 10166774928 10643775488 1104849088 11.884 98 18 36 0 1221733440 113000032 3884320
04:36:59 37119 8192000000 9117372064 9402535936 260815488 3.085 24 3 6 0 286371248 25198304 357456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 17-07-2022 04:39:34 to 17-07-2022 04:39:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 82 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-07-2022 04:39:52 to 17-07-2022 04:39:54.

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          219716
MON$OLDEST_ACTIVE               220004
MON$OLDEST_SNAPSHOT             220004
MON$NEXT_TRANSACTION            220004
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                       2065184
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.2.2799 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2799 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2799 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2799 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'

17.07.22 04:39:55. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-07-17 04:39:57 No severe PSQL-related problems occured
17.07.22 04:39:58. End of report.