Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.12.2022 13:39
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 14530204 kB
MemAvailable 30104424 kB
Buffers 0 kB
Cached 17190464 kB
SwapCached 4068 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-12-17 13:38:27
DTS_END                         2022-12-17 13:37:24.1580
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/51112
TRN_ID                          224699
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: 9529.73 20229 1143568
customer order: creation 179.51 498 21541
customer order: refuse 15.53 395 1864
order to supplier: creation 47.28 1389 5673
order to supplier: removal 3.66 167503 439
invoice (draft): creation 46.13 2933 5536
invoice (draft): removal 53.23 986 6388
invoice accept: apply 43.14 23503 5177
invoice accept: cancel 48.55 1137 5826
customer reserve: creation 4020.71 222 482485
customer reserve: removal 4771.23 102 572547
realization accept: apply 84.85 278 10182
realization accept: cancel 82.28 168 9874
payment from customer: creation 61.13 175 7335
payment from customer: removal 5.93 116 712
payment to supplier: creation 49.83 190 5979
payment to supplier: removal 3.52 128 422
service: total inventory turnovers 4.04 3950 485
service: total monetary turnovers 8.20 321 984
service: aggregate perf. data 0.96 14658 115
service: refresh index statistics 0.03 185936 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-12-2022 13:40:08 to 17-12-2022 13:40:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7574
TEST_TIME 2 5883
TEST_TIME 3 5196
TEST_TIME 4 5599
TEST_TIME 5 5806
TEST_TIME 6 5873
TEST_TIME 7 6083
TEST_TIME 8 6279
TEST_TIME 9 6405
TEST_TIME 10 6385
TEST_TIME 11 6747
TEST_TIME 12 6848
TEST_TIME 13 6769
TEST_TIME 14 7044
TEST_TIME 15 7260
TEST_TIME 16 7351
TEST_TIME 17 7614
TEST_TIME 18 7686
TEST_TIME 19 7659
TEST_TIME 20 7725
TEST_TIME 21 8047
TEST_TIME 22 8147
TEST_TIME 23 8247
TEST_TIME 24 8361
TEST_TIME 25 8679
TEST_TIME 26 8569
TEST_TIME 27 8961
TEST_TIME 28 9543
TEST_TIME 29 9883
TEST_TIME 30 9850
TEST_TIME 31 10097
TEST_TIME 32 10719
TEST_TIME 33 10769
TEST_TIME 34 10658
TEST_TIME 35 10727
TEST_TIME 36 11407
TEST_TIME 37 11284
TEST_TIME 38 11179
TEST_TIME 39 11278
TEST_TIME 40 11094
TEST_TIME 41 10967
TEST_TIME 42 11022
TEST_TIME 43 10908
TEST_TIME 44 10852
TEST_TIME 45 10741
TEST_TIME 46 10564
TEST_TIME 47 10457
TEST_TIME 48 10333
TEST_TIME 49 10239
TEST_TIME 50 10173
TEST_TIME 51 10198
TEST_TIME 52 10095
TEST_TIME 53 10033
TEST_TIME 54 10057
TEST_TIME 55 10045
TEST_TIME 56 10101
TEST_TIME 57 10054
TEST_TIME 58 10109
TEST_TIME 59 10028
TEST_TIME 60 9957
TEST_TIME 61 10022
TEST_TIME 62 9992
TEST_TIME 63 10099
TEST_TIME 64 10088
TEST_TIME 65 10067
TEST_TIME 66 10112
TEST_TIME 67 10102
TEST_TIME 68 10081
TEST_TIME 69 10006
TEST_TIME 70 10090
TEST_TIME 71 10164
TEST_TIME 72 10265
TEST_TIME 73 10217
TEST_TIME 74 10108
TEST_TIME 75 10095
TEST_TIME 76 10042
TEST_TIME 77 9966
TEST_TIME 78 9954
TEST_TIME 79 9956
TEST_TIME 80 9884
TEST_TIME 81 9836
TEST_TIME 82 9762
TEST_TIME 83 9712
TEST_TIME 84 9681
TEST_TIME 85 9584
TEST_TIME 86 9642
TEST_TIME 87 9668
TEST_TIME 88 9658
TEST_TIME 89 9729
TEST_TIME 90 9765
TEST_TIME 91 9728
TEST_TIME 92 9768
TEST_TIME 93 9796
TEST_TIME 94 9801
TEST_TIME 95 9814
TEST_TIME 96 9790
TEST_TIME 97 9800
TEST_TIME 98 9813
TEST_TIME 99 9783
TEST_TIME 100 9721
TEST_TIME 101 9708
TEST_TIME 102 9666
TEST_TIME 103 9640
TEST_TIME 104 9622
TEST_TIME 105 9579
TEST_TIME 106 9550
TEST_TIME 107 9510
TEST_TIME 108 9474
TEST_TIME 109 9462
TEST_TIME 110 9459
TEST_TIME 111 9420
TEST_TIME 112 9468
TEST_TIME 113 9468
TEST_TIME 114 9451
TEST_TIME 115 9491
TEST_TIME 116 9565
TEST_TIME 117 9599
TEST_TIME 118 9656
TEST_TIME 119 9634
TEST_TIME 120 9564

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-12-2022 13:40:12 to 17-12-2022 13:40:15.

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 1586769 1586769 0 0.00 0 30989 12 0 0
doc_list_biud 1586751 1586751 0 0.00 0 39770 42 0 0
sp_add_invoice_to_stock 5317 5177 140 2.63 60 177864 23504 0 140
sp_cancel_adding_invoice 5826 5826 0 0.00 6 67845 1137 0 0
sp_cancel_client_order 1864 1864 0 0.00 6 10899 395 0 0
sp_cancel_customer_reserve 572547 572547 0 0.00 1 39772 103 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 3 10853 116 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 12306 128 0 0
sp_cancel_supplier_invoice 6395 6388 7 0.10 2 20476 986 0 7
sp_cancel_supplier_order 439 439 0 0.00 8 1491777 167504 0 0
sp_cancel_write_off 9874 9874 0 0.00 2 9791 168 0 0
sp_client_order 21541 21541 0 0.00 5 64968 498 0 0
sp_customer_reserve 482485 482485 0 0.00 0 87941 222 0 0
sp_fill_shopping_cart 32739 32739 0 0.00 1 9133 75 0 0
sp_fill_shopping_cart_clo_res 482442 482442 0 0.00 0 12386 17 0 0
sp_get_clo_for_invoice 5122 5122 0 0.00 0 18209 237 0 0
sp_kill_cost_storno 16834 16834 0 0.00 0 30983 79 0 0
sp_kill_qstorno_ret_qs2qd 1161795 1161795 0 0.00 0 39764 44 0 0
sp_kill_qty_storno 612240 612240 0 0.00 0 39770 101 0 0
sp_lock_selected_doc 21346 21346 0 0.00 0 8357 8 0 0
sp_make_cost_storno 28618 28618 0 0.00 0 27923 79 0 0
sp_multiply_rows_for_pdistr 28616 28616 0 0.00 0 7287 37 0 0
sp_multiply_rows_for_qdistr 21541 21541 0 0.00 0 10900 197 0 0
sp_pay_from_customer 7335 7335 0 0.00 5 13672 176 0 0
sp_pay_to_supplier 5979 5979 0 0.00 7 27940 191 0 0
sp_payment_common 13311 13311 0 0.00 2 9628 45 0 0
sp_qd_handle_on_cancel_clo 1864 1864 0 0.00 1 10848 327 0 0
sp_qd_handle_on_invoice_upd_sts 10947 10947 0 0.00 0 12321 473 0 0
sp_qd_handle_on_reserve_upd_sts 10181 10181 0 0.00 0 21775 65 0 0
sp_reserve_write_off 10182 10182 0 0.00 9 21823 279 0 0
sp_supplier_invoice 5536 5536 0 0.00 9 94305 2933 0 0
sp_supplier_order 5673 5673 0 0.00 7 41150 1390 0 0
srv_aggregate_perf_data 115 115 0 0.00 1947 176823 14658 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 753 110705 33182 0 0
srv_find_qd_qs_mism 1092295 1092295 0 0.00 0 13064 9 0 0
srv_make_invnt_saldo 485 485 0 0.00 36 63090 3950 0 0
srv_make_money_saldo 984 984 0 0.00 1 13915 322 0 0
srv_recalc_idx_stat 4 4 0 0.00 63002 312788 185937 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5184 5184 0 0.00 1 11682 842 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 2 5360 356 0 0
t$perf-norm:sp_cancel_client_order 1864 1864 0 0.00 0 3201 26 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 3965 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 1036 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 825 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 595 33 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 91101 7038 0 0
t$perf-norm:sp_cancel_write_off 1109 1109 0 0.00 0 1606 16 0 0
t$perf-norm:sp_client_order 21541 21541 0 0.00 0 5974 33 0 0
t$perf-norm:sp_customer_reserve 1012 1012 0 0.00 0 525 12 0 0
t$perf-norm:sp_pay_from_customer 7335 7335 0 0.00 0 5627 18 0 0
t$perf-norm:sp_pay_to_supplier 5979 5979 0 0.00 0 5873 17 0 0
t$perf-norm:sp_reserve_write_off 10182 10182 0 0.00 0 3344 20 0 0
t$perf-norm:sp_supplier_invoice 5536 5536 0 0.00 0 2871 27 0 0
t$perf-norm:sp_supplier_order 5674 5674 0 0.00 0 7122 32 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 367 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 390 10 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 605 17 0 0
t$perf-norm:srv_make_money_saldo 984 984 0 0.00 0 476 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 138 55 0 0
v_add_invoice_to_stock 10516 10236 280 2.66 0 4387 23 0 280
v_all_customers 39264 39264 0 0.00 0 97 4 0 0
v_all_suppliers 31600 31600 0 0.00 0 116 4 0 0
v_all_wares 43082 43082 0 0.00 0 217 4 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 2525 18 0 0
v_cancel_client_order 3728 3728 0 0.00 0 2176 9 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 2602 13 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 951 10 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 12283 24 0 0
v_cancel_supplier_invoice 802 788 14 1.74 0 1277 23 0 14
v_cancel_supplier_order 878 878 0 0.00 0 418 10 0 0
v_cancel_write_off 2218 2218 0 0.00 0 1831 22 0 0
v_max_id_clo_ord 5671 5671 0 0.00 0 544 5 0 0
v_max_id_clo_res 1012 1012 0 0.00 0 1837 17 0 0
v_max_id_ord_sup 5526 5526 0 0.00 0 7318 10 0 0
v_max_non_paid_invoice 1374 1374 0 0.00 0 486 8 0 0
v_max_non_paid_realizn 3798 3798 0 0.00 0 987 7 0 0
v_min_id_clo_ord 5671 5671 0 0.00 0 4309 9 0 0
v_min_id_clo_res 1012 1012 0 0.00 0 3858 15 0 0
v_min_id_ord_sup 5526 5526 0 0.00 0 4312 10 0 0
v_min_non_paid_invoice 5978 5978 0 0.00 0 9620 16 0 0
v_min_non_paid_realizn 7332 7332 0 0.00 0 5693 18 0 0
v_our_firm 10886 10886 0 0.00 0 76 4 0 0
v_reserve_write_off 20362 20362 0 0.00 0 16140 11 0 0
x_lock_depdocs_on_canc_invoice 11820 11820 0 0.00 0 15992 115 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 2534 289 0 0
x_make_qstorno_1000_1200 5673 5673 0 0.00 2 41141 1238 0 0
x_make_qstorno_1200_2000 5536 5536 0 0.00 4 94291 2730 0 0
x_make_qstorno_2100_3300 482485 482485 0 0.00 0 87929 194 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-12-2022 13:40:16 to 17-12-2022 13:40:20.

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
11:07:27 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:28 18826 8192000000 9705792592 10001809408 787696368 8.771 100 84 168 0 815906368 7325280 20884720
11:09:55 28966 8192000000 9760038608 10066763776 832129792 9.221 100 90 178 0 865633408 12125728 21377888
11:11:36 11846 8192000000 9791304144 10111877120 865842224 9.559 100 79 158 0 903545952 19487520 18216208
11:12:57 10120 8192000000 9858626800 10194534400 917629024 10.073 100 77 152 0 975359520 41209584 16520912
11:14:13 3846 8192000000 9949185360 10293616640 967275008 10.560 100 88 169 0 1055425088 71333472 16816608
11:15:23 1914 8192000000 9973085904 10327703552 992304496 10.804 100 86 168 1 1087781952 78548800 16928656
11:16:27 1371 8192000000 10010844400 10369228800 1021928800 11.091 100 97 190 1 1129235264 87228896 20077568
11:17:31 900 8192000000 10014840768 10385616896 1044695904 11.310 100 94 182 3 1132120368 68930592 18493872
11:18:33 1512 8192000000 10023252880 10396004352 1050073456 11.361 100 92 180 1 1145581920 76851408 18657056
11:19:37 805 8192000000 10028043968 10402320384 1062410576 11.480 100 95 186 1 1147249616 67002944 17836096
11:20:40 1381 8192000000 10041696448 10419703808 1065116864 11.505 100 99 183 2 1160773120 75540576 20115680
11:21:42 1192 8192000000 10050896896 10434916352 1073566640 11.586 100 93 182 1 1173828112 82777136 17484336
11:22:45 1093 8192000000 10042622080 10424942592 1075309088 11.603 100 95 181 2 1164557760 70387680 18860992
11:23:48 1175 8192000000 10050140896 10436681728 1083649200 11.682 100 92 180 1 1173428464 71553744 18225520
11:24:52 1207 8192000000 10056703840 10441121792 1086694784 11.711 100 99 197 1 1176089216 70435040 18959392
11:25:54 1065 8192000000 10046694656 10434387968 1091702528 11.759 100 97 177 1 1172786480 62591104 18492848
11:26:57 1208 8192000000 10051430288 10440765440 1094558768 11.786 100 92 174 0 1176068928 63021232 18488928
11:27:59 1435 8192000000 10060007760 10448691200 1097008208 11.809 100 94 173 1 1185850192 71613824 17228160
11:29:01 2650 8192000000 10033329328 10420965376 1088051728 11.724 99 72 140 1 1159229584 56647840 14530016
11:30:05 922 8192000000 10017483248 10406146048 1084239872 11.688 100 85 150 1 1141453360 40501984 16711504
11:31:07 1612 8192000000 10039313184 10426572800 1063320832 11.488 99 76 145 0 1168126560 89926032 14879696
11:32:10 1133 8192000000 10029542800 10410344448 1066780752 11.521 100 98 193 0 1150935776 62700688 21454336
11:33:12 845 8192000000 10006459744 10390056960 1052332384 11.383 100 64 117 3 1125409296 61433616 11643296
11:34:14 1322 8192000000 9969467104 10343391232 1031270096 11.181 99 84 163 2 1088120000 40415792 16434112
11:35:16 1045 8192000000 9999903504 10378379264 1055823680 11.416 100 91 168 3 1126741872 52226272 18691920
11:36:19 1167 8192000000 9987535824 10360659968 1033603456 11.203 100 91 170 0 1111734224 59122144 19008624
11:37:21 1184 8192000000 10024592704 10397089792 1050571760 11.366 100 97 189 0 1149977168 78427280 20978128
11:38:33 3691 8192000000 10016817824 10398883840 1063161168 11.487 100 65 127 1 1145544736 70317904 12065664
11:39:44 8708 8192000000 10036614784 10415853568 1072830448 11.579 100 73 143 1 1153964064 66786576 14347040
11:41:24 24836 8192000000 10067800048 10458685440 1090192288 11.744 100 64 126 0 1191925040 89038240 12694512
11:42:57 12364 8192000000 10119607632 10513723392 1114156944 11.972 100 63 120 2 1225308720 98531824 12619952
11:44:16 1962 8192000000 10146139600 10537836544 1122463952 12.050 100 87 167 1 1257835904 116800176 18571776
11:45:22 7019 8192000000 10179903856 10575998976 1130597888 12.127 100 79 149 0 1300161472 153760304 15803280
11:46:36 1133 8192000000 10227794464 10620088320 1138541104 12.202 100 98 191 0 1338889488 179933744 20414640
11:47:41 945 8192000000 10244717888 10650517504 1149008432 12.300 100 98 193 0 1360612992 189860320 21744240
11:48:44 1139 8192000000 10288080080 10687143936 1159555408 12.399 100 99 188 0 1401416768 220355088 21506272
11:49:49 793 8192000000 10298270272 10697396224 1163691392 12.438 100 89 173 0 1409050368 225979664 19379312
11:50:52 1621 8192000000 10337384960 10750267392 1162057344 12.423 99 81 149 2 1444480512 265311136 17112032
11:51:58 1321 8192000000 10355504496 10775175168 1168450688 12.482 99 85 167 0 1464088912 276140512 19497712
11:53:05 1617 8192000000 10354747152 10786676736 1175383504 12.547 100 89 164 2 1482220192 288154976 18681712
11:54:08 1078 8192000000 10407032608 10839711744 1186186720 12.648 100 96 188 0 1514464688 306912544 21365424
11:55:13 1634 8192000000 10418974480 10828070912 1187821056 12.663 100 80 155 3 1531556080 324608224 19126800
11:56:19 1004 8192000000 10478720800 10901639168 1189678016 12.680 100 84 166 1 1588050032 378550704 19821312
11:57:23 803 8192000000 10477455696 10899775488 1184385456 12.631 100 95 182 0 1581957760 375461904 22110400
11:58:26 742 8192000000 10499159776 10939047936 1181369312 12.603 100 95 187 1 1601450448 397552848 22528288
11:59:29 866 8192000000 10515731376 10928992256 1182429520 12.613 100 82 162 0 1621124496 418840976 19854000
12:00:32 1655 8192000000 10545857744 10965020672 1181860880 12.608 100 69 133 0 1646381264 447700224 16820160
12:01:39 6207 8192000000 10546087024 10992189440 1184859040 12.635 100 57 112 0 1652821680 453447520 14515120
12:02:54 30570 8192000000 10546494144 11000041472 1169695056 12.494 100 33 66 0 1653266720 474838464 8733200
12:04:29 9701 8192000000 10483215248 10976292864 1158927712 12.393 100 39 75 1 1597720224 429027728 9764784
12:06:03 24502 8192000000 10364146240 10829860864 1143017712 12.244 98 42 82 0 1453702176 300877552 9806912
12:07:29 9411 8192000000 10381860160 10844946432 1151540288 12.324 100 34 68 0 1482212880 322090480 8582112
12:08:43 22549 8192000000 10256666352 10793480192 1164695472 12.447 100 50 97 0 1383954272 207241360 12017440
12:10:07 31632 8192000000 10288346944 10760470528 1168594832 12.484 99 29 58 0 1361079392 185757184 6727376
12:11:40 23170 8192000000 10296355936 10740850688 1119622512 12.023 97 34 66 0 1365392432 237714720 8055200
12:13:07 14726 8192000000 10194536640 10778120192 1117455904 12.003 99 18 36 0 1328402384 205720640 5225840
12:14:24 23736 8192000000 10062685856 10489065472 1133880496 12.158 100 43 81 1 1172350560 28143792 10326272
12:15:53 83405 8192000000 10086242864 10596159488 1142266992 12.237 100 13 24 0 1205356944 60909552 2180400
12:18:17 110705 8192000000 10082184112 10566299648 1101560048 11.852 96 18 32 1 1158685888 54281760 2844080
12:21:08 106080 8192000000 10065142320 10507948032 1111915136 11.951 98 20 40 0 1147296224 30854672 4526416
12:24:00 67630 8192000000 10052026064 10482425856 1068710368 11.540 95 40 71 0 1090876304 14051248 8114688
12:26:09 67463 8192000000 10092429312 10524155904 1121091408 12.037 99 50 91 2 1162831200 31552432 10187360
12:28:18 12058 8192000000 10114720016 10555506688 1130220320 12.123 99 34 66 0 1205400352 68214224 6965808
12:29:35 16763 8192000000 10139328464 10562805760 1149249408 12.302 100 54 105 1 1225154480 63139872 12765200
12:30:52 26594 8192000000 10176917600 10641207296 1136787712 12.185 99 50 97 0 1302927312 155101696 11037904
12:32:19 61609 8192000000 10154804896 10627858432 1146523840 12.277 100 22 42 0 1265147328 113509360 5114128
12:34:22 25986 8192000000 10118250656 10587545600 1135251904 12.171 99 20 40 0 1218108464 78359024 4497536
12:35:54 21469 8192000000 10067180224 10544738304 1137159120 12.189 100 13 26 0 1166122768 26152080 2811568
12:37:16 51505 8192000000 10086980688 10593255424 1134685760 12.166 98 13 22 1 1214039072 77620000 1733312
12:39:20 21325 8192000000 10116446272 10553307136 1157457360 12.379 100 30 53 0 1216029184 54069424 4502400
12:40:57 66661 8192000000 10103014272 10576859136 1112887360 11.960 96 23 45 0 1181885520 64257792 4740368
12:43:04 43017 8192000000 10114170816 10597978112 1129489040 12.117 99 21 42 0 1214274288 80410528 4374720
12:44:48 14072 8192000000 10114220944 10544947200 1138066448 12.197 99 51 97 1 1182716272 33435632 11214192
12:46:08 44932 8192000000 10181086304 10612592640 1150928512 12.318 100 21 39 1 1275185184 119471264 4785408
12:47:55 108653 8192000000 10082881792 10599837696 1105164112 11.887 96 16 32 0 1251742976 142976992 3601872
12:50:48 89778 8192000000 10135679280 10577805312 1130126768 12.123 99 27 54 0 1212465088 76004416 6333904
12:53:32 27715 8192000000 10068874752 10504372224 1120139648 12.028 98 34 64 1 1146772464 20685616 5947200
12:55:01 25553 8192000000 10140344304 10603683840 1139291360 12.209 100 46 90 0 1231718144 83477872 8948912
12:56:29 51948 8192000000 10073748608 10510020608 1110393840 11.936 97 18 35 0 1137441568 23379696 3668032
12:58:22 67254 8192000000 10116550080 10572095488 1139805152 12.214 99 28 52 1 1211359280 65140928 6413200
13:00:30 50605 8192000000 10172893920 10621485056 1163247680 12.434 100 25 50 0 1282575968 113891936 5436352
13:02:32 43040 8192000000 10171181472 10650652672 1129253424 12.114 99 38 75 0 1285033472 147720832 8059216
13:04:23 80582 8192000000 10130526064 10603433984 1124176112 12.066 99 16 33 0 1244719584 116951152 3592320
13:06:44 38981 8192000000 10135289104 10577731584 1136010704 12.178 99 45 88 0 1209220192 63883936 9325552
13:08:37 52903 8192000000 10110633520 10550562816 1102717968 11.863 97 29 54 3 1145346272 36075504 6552800
13:10:32 89046 8192000000 10075899232 10512932864 1077652080 11.625 95 20 38 0 1126458672 45361776 3444816
13:13:03 27260 8192000000 10107667488 10534940672 1121129504 12.038 99 49 93 0 1192641728 61964016 9548208
13:14:38 48986 8192000000 10101071728 10578161664 1130020240 12.122 100 27 54 0 1200048560 64839984 5188336
13:16:27 80514 8192000000 10105450704 10538848256 1119163296 12.019 98 55 110 0 1180734176 49504384 12066496
13:18:53 35383 8192000000 10105864240 10595864576 1137932384 12.196 100 25 50 0 1211233840 68124192 5177264
13:20:31 35282 8192000000 10091395712 10590388224 1135766512 12.176 100 6 12 0 1218369296 81203552 1399232
13:22:07 61416 8192000000 10079987296 10529288192 1107363856 11.907 97 13 25 0 1154783584 44909840 2509888
13:24:25 57530 8192000000 10137143856 10592563200 1136531184 12.183 99 17 34 0 1229296272 89193456 3571632
13:26:23 28865 8192000000 10169619904 10609811456 1130191984 12.123 99 27 54 0 1238303120 102265232 5845904
13:27:59 20400 8192000000 10191629664 10650554368 1158479856 12.389 100 31 62 0 1287138112 121357808 7300448
13:29:22 35500 8192000000 10216446400 10691518464 1137246784 12.190 98 35 70 0 1309072592 163709520 8116288
13:31:00 14212 8192000000 10200861680 10647191552 1130077936 12.122 99 38 76 0 1283602576 144987568 8537072
13:32:20 67591 8192000000 10160780368 10642202624 1122151888 12.047 98 24 48 1 1286145856 158021888 5972080
13:34:30 40001 8192000000 10088335824 10635296768 1118013104 12.008 99 17 34 0 1223358544 101088464 4256976
13:36:11 30628 8192000000 10113444160 10537869312 1109865680 11.931 99 45 90 0 1168164160 49400128 8898352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-12-2022 13:40:22 to 17-12-2022 13:40:38.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-12-2022 13:40:39 to 17-12-2022 13:40:41.

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          230495
MON$OLDEST_ACTIVE               230496
MON$OLDEST_SNAPSHOT             230496
MON$NEXT_TRANSACTION            230496
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                       2087712
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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.12.22 13:40:42. 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-12-17 13:40:45 No severe PSQL-related problems occured
17.12.22 13:40:46. End of report.