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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-22 13:37:24
DTS_END                         2023-01-22 13:37:23.9960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39208
TRN_ID                          214752
ATT_ID                          649
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: 9078.85 21651 1089462
customer order: creation 176.53 495 21184
customer order: refuse 15.60 472 1872
order to supplier: creation 47.38 1398 5686
order to supplier: removal 3.46 175314 415
invoice (draft): creation 47.91 3265 5749
invoice (draft): removal 50.65 1096 6078
invoice accept: apply 42.28 27553 5073
invoice accept: cancel 45.36 1484 5443
customer reserve: creation 3924.58 258 470950
customer reserve: removal 4428.17 107 531380
realization accept: apply 84.81 316 10177
realization accept: cancel 79.09 179 9491
payment from customer: creation 61.49 192 7379
payment from customer: removal 5.91 163 709
payment to supplier: creation 49.03 205 5884
payment to supplier: removal 3.87 159 464
service: total inventory turnovers 4.12 4623 494
service: total monetary turnovers 7.61 399 913
service: aggregate perf. data 0.97 16361 116
service: refresh index statistics 0.04 198985 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-01-2023 13:38:46 to 22-01-2023 13:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7408
TEST_TIME 2 5393
TEST_TIME 3 5988
TEST_TIME 4 5662
TEST_TIME 5 6160
TEST_TIME 6 5979
TEST_TIME 7 6082
TEST_TIME 8 6241
TEST_TIME 9 6578
TEST_TIME 10 6686
TEST_TIME 11 6686
TEST_TIME 12 6658
TEST_TIME 13 6552
TEST_TIME 14 6645
TEST_TIME 15 6689
TEST_TIME 16 6861
TEST_TIME 17 7186
TEST_TIME 18 7632
TEST_TIME 19 7641
TEST_TIME 20 7750
TEST_TIME 21 7822
TEST_TIME 22 7837
TEST_TIME 23 7911
TEST_TIME 24 7944
TEST_TIME 25 8122
TEST_TIME 26 8040
TEST_TIME 27 8100
TEST_TIME 28 8274
TEST_TIME 29 8293
TEST_TIME 30 8664
TEST_TIME 31 8939
TEST_TIME 32 8974
TEST_TIME 33 9234
TEST_TIME 34 9436
TEST_TIME 35 9629
TEST_TIME 36 9632
TEST_TIME 37 10026
TEST_TIME 38 10058
TEST_TIME 39 10258
TEST_TIME 40 10558
TEST_TIME 41 10909
TEST_TIME 42 10840
TEST_TIME 43 10646
TEST_TIME 44 10553
TEST_TIME 45 10512
TEST_TIME 46 10466
TEST_TIME 47 10448
TEST_TIME 48 10317
TEST_TIME 49 10318
TEST_TIME 50 10229
TEST_TIME 51 10273
TEST_TIME 52 10173
TEST_TIME 53 10007
TEST_TIME 54 9886
TEST_TIME 55 9921
TEST_TIME 56 9822
TEST_TIME 57 9937
TEST_TIME 58 9974
TEST_TIME 59 10020
TEST_TIME 60 9967
TEST_TIME 61 9967
TEST_TIME 62 9874
TEST_TIME 63 9827
TEST_TIME 64 9721
TEST_TIME 65 9681
TEST_TIME 66 9620
TEST_TIME 67 9622
TEST_TIME 68 9604
TEST_TIME 69 9547
TEST_TIME 70 9534
TEST_TIME 71 9527
TEST_TIME 72 9560
TEST_TIME 73 9625
TEST_TIME 74 9624
TEST_TIME 75 9591
TEST_TIME 76 9579
TEST_TIME 77 9619
TEST_TIME 78 9729
TEST_TIME 79 9747
TEST_TIME 80 9741
TEST_TIME 81 9724
TEST_TIME 82 9654
TEST_TIME 83 9602
TEST_TIME 84 9517
TEST_TIME 85 9489
TEST_TIME 86 9433
TEST_TIME 87 9456
TEST_TIME 88 9423
TEST_TIME 89 9364
TEST_TIME 90 9297
TEST_TIME 91 9324
TEST_TIME 92 9345
TEST_TIME 93 9331
TEST_TIME 94 9293
TEST_TIME 95 9321
TEST_TIME 96 9268
TEST_TIME 97 9326
TEST_TIME 98 9340
TEST_TIME 99 9397
TEST_TIME 100 9413
TEST_TIME 101 9408
TEST_TIME 102 9409
TEST_TIME 103 9398
TEST_TIME 104 9448
TEST_TIME 105 9438
TEST_TIME 106 9394
TEST_TIME 107 9340
TEST_TIME 108 9321
TEST_TIME 109 9331
TEST_TIME 110 9285
TEST_TIME 111 9237
TEST_TIME 112 9189
TEST_TIME 113 9180
TEST_TIME 114 9158
TEST_TIME 115 9172
TEST_TIME 116 9183
TEST_TIME 117 9165
TEST_TIME 118 9140
TEST_TIME 119 9133
TEST_TIME 120 9106

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-01-2023 13:38:50 to 22-01-2023 13:38:53.

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 1523407 1523407 0 0.00 0 15191 14 0 0
doc_list_biud 1523396 1523396 0 0.00 0 25605 44 0 0
sp_add_invoice_to_stock 5185 5073 112 2.16 45 186458 27553 0 112
sp_cancel_adding_invoice 5443 5443 0 0.00 7 89416 1484 0 0
sp_cancel_client_order 1872 1872 0 0.00 6 9483 473 0 0
sp_cancel_customer_reserve 531380 531380 0 0.00 1 23485 108 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 4 9009 163 0 0
sp_cancel_pay_to_supplier 464 464 0 0.00 4 4355 160 0 0
sp_cancel_supplier_invoice 6089 6078 11 0.18 3 25620 1096 0 11
sp_cancel_supplier_order 415 415 0 0.00 14 1777558 175314 0 0
sp_cancel_write_off 9491 9491 0 0.00 2 11995 179 0 0
sp_client_order 21184 21184 0 0.00 6 27304 495 0 0
sp_customer_reserve 470950 470950 0 0.00 1 53844 259 0 0
sp_fill_shopping_cart 32617 32617 0 0.00 2 20144 91 0 0
sp_fill_shopping_cart_clo_res 470916 470916 0 0.00 0 15874 20 0 0
sp_get_clo_for_invoice 5027 5027 0 0.00 0 33905 285 0 0
sp_kill_cost_storno 16107 16107 0 0.00 0 6445 81 0 0
sp_kill_qstorno_ret_qs2qd 1078744 1078744 0 0.00 0 25600 46 0 0
sp_kill_qty_storno 569883 569883 0 0.00 0 25604 108 0 0
sp_lock_selected_doc 21205 21205 0 0.00 0 8999 8 0 0
sp_make_cost_storno 28467 28467 0 0.00 0 15183 84 0 0
sp_multiply_rows_for_pdistr 28467 28467 0 0.00 0 7108 42 0 0
sp_multiply_rows_for_qdistr 21184 21184 0 0.00 0 16830 182 0 0
sp_pay_from_customer 7379 7379 0 0.00 5 11886 192 0 0
sp_pay_to_supplier 5884 5884 0 0.00 7 10187 206 0 0
sp_payment_common 13263 13263 0 0.00 2 7365 50 0 0
sp_qd_handle_on_cancel_clo 1872 1872 0 0.00 1 9471 386 0 0
sp_qd_handle_on_invoice_upd_sts 10470 10470 0 0.00 0 18478 603 0 0
sp_qd_handle_on_reserve_upd_sts 10177 10177 0 0.00 0 13926 76 0 0
sp_reserve_write_off 10177 10177 0 0.00 9 15205 317 0 0
sp_supplier_invoice 5749 5749 0 0.00 10 104860 3266 0 0
sp_supplier_order 5686 5686 0 0.00 7 31115 1399 0 0
srv_aggregate_perf_data 116 116 0 0.00 2139 168915 16362 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 844 126959 30080 0 0
srv_find_qd_qs_mism 1039432 1039432 0 0.00 0 9607 10 0 0
srv_make_invnt_saldo 494 494 0 0.00 89 62433 4624 0 0
srv_make_money_saldo 913 913 0 0.00 1 10516 400 0 0
srv_recalc_idx_stat 5 5 0 0.00 63257 324184 198985 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5082 5082 0 0.00 1 14109 1152 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 1 8800 649 0 0
t$perf-norm:sp_cancel_client_order 1872 1872 0 0.00 0 1702 32 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 2818 42 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 1937 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 464 464 0 0.00 0 2098 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 734 40 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 141502 8543 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 1259 20 0 0
t$perf-norm:sp_client_order 21184 21184 0 0.00 0 7609 46 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 2895 20 0 0
t$perf-norm:sp_pay_from_customer 7379 7379 0 0.00 0 6813 26 0 0
t$perf-norm:sp_pay_to_supplier 5884 5884 0 0.00 0 8209 24 0 0
t$perf-norm:sp_reserve_write_off 10177 10177 0 0.00 0 6639 27 0 0
t$perf-norm:sp_supplier_invoice 5749 5749 0 0.00 0 7651 39 0 0
t$perf-norm:sp_supplier_order 5686 5686 0 0.00 0 3923 39 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 3961 46 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 836 30 0 0
t$perf-norm:srv_make_invnt_saldo 494 494 0 0.00 0 988 17 0 0
t$perf-norm:srv_make_money_saldo 913 913 0 0.00 0 327 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 603 165 0 0
v_add_invoice_to_stock 10277 10053 224 2.17 0 4699 30 0 224
v_all_customers 39054 39054 0 0.00 0 70 5 0 0
v_all_suppliers 31498 31498 0 0.00 0 72 5 0 0
v_all_wares 42368 42368 0 0.00 0 83 5 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 5562 28 0 0
v_cancel_client_order 3744 3744 0 0.00 0 2158 12 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 5392 19 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 1581 16 0 0
v_cancel_payment_to_supplier 928 928 0 0.00 0 3410 20 0 0
v_cancel_supplier_invoice 876 854 22 2.51 0 2391 27 0 22
v_cancel_supplier_order 830 830 0 0.00 0 1964 13 0 0
v_cancel_write_off 1940 1940 0 0.00 0 4947 30 0 0
v_max_id_clo_ord 5686 5686 0 0.00 0 527 6 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 2344 15 0 0
v_max_id_ord_sup 5746 5746 0 0.00 0 5954 8 0 0
v_max_non_paid_invoice 1567 1567 0 0.00 0 96 9 0 0
v_max_non_paid_realizn 3747 3747 0 0.00 0 820 8 0 0
v_min_id_clo_ord 5686 5686 0 0.00 0 3098 9 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 8733 27 0 0
v_min_id_ord_sup 5746 5746 0 0.00 0 4826 10 0 0
v_min_non_paid_invoice 5884 5884 0 0.00 0 7347 14 0 0
v_min_non_paid_realizn 7379 7379 0 0.00 0 5520 19 0 0
v_our_firm 10578 10578 0 0.00 0 64 5 0 0
v_reserve_write_off 20354 20354 0 0.00 0 3368 12 0 0
x_lock_depdocs_on_canc_invoice 11094 11094 0 0.00 0 18395 143 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 4050 375 0 0
x_make_qstorno_1000_1200 5686 5686 0 0.00 2 31100 1193 0 0
x_make_qstorno_1200_2000 5749 5749 0 0.00 4 102012 3034 0 0
x_make_qstorno_2100_3300 470947 470947 0 0.00 0 45902 224 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 22-01-2023 13:38:54 to 22-01-2023 13:38:59.

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 3 8192000000 8800835312 8812818432 6419632 0.078 2 2 3 0 6571584 90592 61360
11:08:28 26173 8192000000 9721833936 10020532224 794626960 8.842 100 87 174 0 823715440 7908240 21180240
11:10:01 1840 8192000000 9760223504 10066022400 838727232 9.287 100 94 188 0 872089888 11001248 22361408
11:11:08 23257 8192000000 9786529200 10099937280 856221360 9.462 100 88 174 0 893504784 17185696 20097728
11:12:52 15275 8192000000 9841918576 10174148608 899139888 9.890 100 79 152 1 950006656 34014224 16852544
11:14:11 7630 8192000000 9892857424 10235621376 944662240 10.339 100 78 156 0 1006919984 46039264 16218480
11:15:21 2664 8192000000 9955083584 10309775360 988001120 10.762 100 86 167 0 1067029808 62404368 16624320
11:16:27 5638 8192000000 9994517104 10352181248 1009346560 10.969 100 87 170 0 1114776224 88800096 16629568
11:17:35 2022 8192000000 10010031200 10376048640 1035164704 11.218 100 85 165 2 1132738672 81123904 16450064
11:18:39 3699 8192000000 10009148496 10379821056 1052785792 11.387 100 93 178 1 1133402224 62549376 18067056
11:19:45 1665 8192000000 10014580208 10397364224 1059403024 11.451 100 84 165 0 1137269584 60740960 17125600
11:20:50 919 8192000000 10045525888 10427338752 1066957296 11.523 100 92 177 0 1170845024 86070688 17817040
11:21:56 2839 8192000000 10040730096 10416402432 1070940736 11.561 100 85 163 2 1166989792 80300208 15748848
11:23:02 1700 8192000000 10040316208 10420744192 1074908400 11.599 100 97 184 2 1158841280 65121632 18811248
11:24:07 1284 8192000000 10064096240 10446348288 1084764544 11.693 100 95 173 2 1184625424 82394288 17466592
11:25:12 1176 8192000000 10069775280 10456989696 1087798480 11.722 100 98 188 2 1197199232 89681936 19718816
11:26:15 1201 8192000000 10074880800 10461605888 1091438560 11.756 100 98 185 2 1196098432 84905984 19753888
11:27:19 1294 8192000000 10052171376 10440806400 1091924112 11.761 100 94 179 1 1175964224 64910096 19130016
11:28:23 1299 8192000000 10086795168 10475692032 1101200624 11.849 100 98 189 1 1211259840 90213680 19845536
11:29:26 1018 8192000000 10062531168 10455334912 1103098352 11.867 100 93 179 0 1184467328 63595248 17773728
11:30:28 1074 8192000000 10082433648 10473644032 1092907600 11.770 100 97 193 0 1201831856 89415648 19508608
11:31:30 1190 8192000000 10060549296 10446299136 1089852080 11.741 100 91 175 0 1183711936 75070480 18789376
11:32:33 1190 8192000000 10044050656 10427363328 1077064608 11.619 100 95 179 1 1166430800 70164720 19201472
11:33:35 964 8192000000 10000351504 10380066816 1066670112 11.520 100 93 176 0 1126320960 40537152 19113696
11:34:38 1984 8192000000 9987401984 10366005248 1036276112 11.229 99 82 156 1 1112560736 59082640 17201984
11:35:42 1354 8192000000 9998552336 10365231104 1030873840 11.177 100 93 179 1 1123989408 73869648 19245920
11:36:44 1073 8192000000 9998893440 10373570560 1038384960 11.249 99 83 157 2 1120425216 66445120 15595136
11:37:48 6122 8192000000 9984404848 10366066688 1049391200 11.355 100 68 132 0 1101685760 39270000 13024560
11:39:02 6372 8192000000 10016524288 10392514560 1061122288 11.467 100 79 140 3 1136659968 61295488 14242192
11:40:18 2509 8192000000 10058056064 10445037568 1081589712 11.663 100 83 155 1 1182848896 84764272 16494912
11:41:26 5850 8192000000 10060849936 10452099072 1092240112 11.764 100 66 127 0 1180971264 75574704 13156448
11:42:38 867 8192000000 10092515952 10477920256 1096395360 11.803 99 86 169 2 1214567440 98934384 19237696
11:43:40 4285 8192000000 10149635104 10541211648 1120735232 12.034 100 73 139 1 1269448736 134043120 14670384
11:44:48 2178 8192000000 10189078352 10581753856 1121358240 12.040 100 78 140 0 1300451504 164295584 14797680
11:46:01 2074 8192000000 10199822368 10601406464 1134295552 12.162 100 85 157 2 1315384896 163957184 17132160
11:47:08 2014 8192000000 10217946032 10614747136 1148206384 12.293 100 87 170 1 1335481264 169271200 18003680
11:48:16 1802 8192000000 10247331136 10656108544 1157765024 12.382 100 81 161 1 1363043648 187694160 17584464
11:49:21 1032 8192000000 10282973168 10690428928 1169772416 12.495 100 80 158 0 1398983488 211705888 17505184
11:50:26 15215 8192000000 10326181168 10737545216 1164381024 12.444 100 77 154 0 1437430576 254904576 18144976
11:51:44 1766 8192000000 10363072704 10768674816 1170345184 12.500 100 88 171 0 1473332608 282180032 20807392
11:52:52 2258 8192000000 10383450768 10792304640 1176453440 12.557 100 94 171 2 1498887152 302013328 20420384
11:53:58 1185 8192000000 10353307696 10813300736 1184500432 12.632 100 93 178 0 1462181376 256783264 20897680
11:55:03 1346 8192000000 10368415744 10811850752 1183322544 12.621 100 91 177 0 1479553888 276352336 19879008
11:56:07 2701 8192000000 10372957744 10799677440 1184997024 12.637 100 76 149 1 1486051184 283889440 17164720
11:57:16 1058 8192000000 10410345456 10820329472 1186454896 12.650 100 90 179 0 1513616304 306284656 20876752
11:58:23 873 8192000000 10409249840 10823446528 1185394800 12.640 100 84 167 0 1514716368 308770112 20551456
11:59:28 2635 8192000000 10439467120 10867154944 1188340752 12.668 100 90 171 2 1549379600 340363168 20675680
12:00:36 1545 8192000000 10444525328 10867507200 1192598944 12.708 100 96 190 0 1550074704 335079360 22396400
12:01:40 894 8192000000 10475185264 10893340672 1186285936 12.649 100 96 191 2 1580065056 370641232 23137888
12:02:45 830 8192000000 10478304000 10901696512 1173067616 12.525 100 99 193 2 1582848656 387849744 21931296
12:03:49 1388 8192000000 10508462144 10955927552 1187625904 12.661 100 95 184 0 1611007088 401207472 22173712
12:04:55 1774 8192000000 10496772592 10960670720 1176833184 12.561 100 97 188 0 1595307840 395354432 23120224
12:06:00 1507 8192000000 10523230592 10976669696 1175606496 12.549 100 86 170 1 1633972848 436491776 21874576
12:07:08 1183 8192000000 10524061264 10960613376 1180856624 12.598 100 83 166 1 1628367024 426543536 20966864
12:08:10 7226 8192000000 10441196768 10959142912 1172775376 12.523 100 46 92 0 1532635392 347515760 12344256
12:09:25 6310 8192000000 10451457136 10875822080 1168137968 12.479 99 62 122 0 1551636432 368680368 14818096
12:10:34 21231 8192000000 10398244176 10895884288 1158717536 12.391 99 34 66 0 1546331840 379115840 8498464
12:11:59 9554 8192000000 10373122848 10844348416 1151541104 12.324 99 32 64 0 1464462096 305123312 7797680
12:13:12 7917 8192000000 10333481456 10803245056 1152215360 12.330 100 43 82 2 1450006368 287710944 10080064
12:14:24 25451 8192000000 10294178096 10772148224 1154914672 12.356 100 42 81 1 1380823296 216845776 9062848
12:15:54 26807 8192000000 10256108336 10769952768 1135348704 12.172 100 23 45 0 1384797232 243657440 5791088
12:18:31 75932 8192000000 10062365344 10592014336 1080814144 11.655 97 9 18 0 1145005488 62278464 1912880
12:20:48 32550 8192000000 10060685808 10504114176 1104314912 11.879 99 47 81 1 1158286848 46069568 7902368
12:22:22 125575 8192000000 10080812544 10555494400 1093185792 11.773 97 27 50 2 1160096368 61839408 5071168
12:25:34 2941 8192000000 10074005328 10506588160 1127861856 12.101 100 58 98 3 1172390544 33891360 10637328
12:26:38 60593 8192000000 10081453728 10522001408 1119921696 12.026 99 57 101 0 1174619920 44131232 10566992
12:29:00 113512 8192000000 10127933616 10564386816 1128343808 12.106 99 20 39 0 1191182608 58522080 4316720
12:32:00 1724 8192000000 10217966208 10634428416 1156857184 12.374 100 84 160 1 1310089056 135649600 17582272
12:33:03 13354 8192000000 10206077504 10643197952 1160726176 12.410 100 39 78 0 1297233600 127912784 8594640
12:34:24 26753 8192000000 10187737584 10670350336 1150465744 12.314 100 48 90 2 1333859200 172698880 10694576
12:36:38 22811 8192000000 10073196816 10559188992 1135607952 12.174 100 8 17 0 1186665424 49271120 1786352
12:38:02 30093 8192000000 10067296304 10539679744 1134811952 12.167 100 19 38 1 1178746496 39717264 4217280
12:39:32 52931 8192000000 10078542496 10544103424 1132996192 12.150 99 13 26 0 1176950976 40545392 3409392
12:41:41 79789 8192000000 10086856816 10557026304 1139370400 12.210 99 28 54 0 1191375056 46960832 5043824
12:44:10 45583 8192000000 10086567696 10593951744 1135140944 12.170 99 17 34 0 1208147904 69572912 3434048
12:46:05 24123 8192000000 10091739872 10554761216 1139432560 12.210 99 30 55 1 1189026288 44360208 5233520
12:47:37 36569 8192000000 10135675584 10616299520 1135472640 12.173 99 26 52 0 1225524272 84074336 5977296
12:49:16 34505 8192000000 10123719408 10593136640 1119855168 12.026 98 32 63 1 1206469696 80280272 6334256
12:50:53 52440 8192000000 10122660272 10552020992 1097996864 11.819 97 50 98 0 1180520992 71850624 10673504
12:52:48 33375 8192000000 10142005872 10602459136 1127261952 12.096 99 38 76 0 1239538192 103992592 8283648
12:54:21 126959 8192000000 10091250448 10577817600 1109945984 11.932 98 24 46 0 1271868544 156399584 5522976
12:57:29 39023 8192000000 10076103376 10532421632 1094146784 11.782 97 34 66 0 1138847824 37914416 6786624
12:59:08 125066 8192000000 10055801776 10506043392 1102998976 11.866 98 20 40 0 1156206736 48918624 4289136
13:02:20 85286 8192000000 10098550928 10584211456 1105841232 11.893 98 23 46 0 1201190128 90516768 4832128
13:04:50 43655 8192000000 10061462096 10504904704 1107416144 11.908 99 20 40 0 1152800960 41074208 4310608
13:06:37 67232 8192000000 10102659264 10558935040 1118879424 12.016 100 11 22 0 1204059280 82902224 2277632
13:09:09 63588 8192000000 10043905312 10505977856 1095168496 11.792 98 13 26 0 1144849216 47147888 2532832
13:11:13 22553 8192000000 10063972784 10500608000 1105857904 11.893 99 30 54 1 1148878496 37464560 5556032
13:12:38 40232 8192000000 10081613440 10517823488 1115685424 11.986 99 21 42 0 1172231376 52270272 4275680
13:14:21 67052 8192000000 10171594816 10651275264 1120977248 12.036 99 27 54 0 1237914704 111325392 5612064
13:16:28 34127 8192000000 10096923248 10565529600 1113796896 11.968 99 37 74 0 1183622976 61815792 8010288
13:18:06 23619 8192000000 10090843024 10522869760 1111163056 11.943 98 40 80 1 1168510768 49481712 7866000
13:19:30 15055 8192000000 10098847312 10547539968 1129710688 12.119 100 35 68 2 1186389776 49961312 6717776
13:20:49 34857 8192000000 10119202304 10577551360 1126460256 12.088 99 41 78 1 1207521024 72406528 8654240
13:22:29 8730 8192000000 10086735568 10517680128 1131075760 12.132 100 43 86 1 1190843968 50519616 9248592
13:23:40 115981 8192000000 10114350704 10573811712 1139521680 12.211 100 29 58 0 1222610496 76593408 6495408
13:26:47 46127 8192000000 10073169552 10569781248 1118148304 12.009 98 20 38 1 1171714272 50326752 3239216
13:28:33 63620 8192000000 10123077232 10585632768 1146240576 12.274 100 16 32 0 1202157312 52323776 3592960
13:31:28 71547 8192000000 10097241680 10566291456 1119961664 12.027 98 15 31 0 1205096736 81823280 3311792
13:33:55 42532 8192000000 10088542160 10544263168 1116519296 11.994 97 24 48 1 1163392096 41638864 5233936
13:35:42 45759 8192000000 10132365104 10599051264 1129280528 12.115 98 26 52 0 1210042464 74971312 5790624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-01-2023 13:39:01 to 22-01-2023 13:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-01-2023 13:39:18 to 22-01-2023 13:39:20.

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          214749
MON$OLDEST_ACTIVE               218833
MON$OLDEST_SNAPSHOT             218833
MON$NEXT_TRANSACTION            218833
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                       2113280
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:994: 
:995: mscs-test-161.budzdorov.ru    Sun Jan 22 13:37:24 2023
:996:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:997: 
:998: 
:999: mscs-test-161.budzdorov.ru    Sun Jan 22 13:37:27 2023
:1000:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1001: 
:1002: 
:1003: mscs-test-161.budzdorov.ru    Sun Jan 22 13:37:35 2023
:1004:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1005: 
--- end of diff output ---

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

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