Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.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 15092644 kB
MemAvailable 30425844 kB
Buffers 0 kB
Cached 16640712 kB
SwapCached 4464 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-13 13:37:24
DTS_END                         2023-01-13 13:37:24.4390
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/42194
TRN_ID                          215230
ATT_ID                          621
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: 8851.79 28900 1062215
customer order: creation 166.93 489 20032
customer order: refuse 15.11 459 1813
order to supplier: creation 44.95 1489 5394
order to supplier: removal 3.52 163250 422
invoice (draft): creation 45.54 3068 5465
invoice (draft): removal 48.96 1090 5875
invoice accept: apply 42.03 29265 5044
invoice accept: cancel 43.98 1303 5278
customer reserve: creation 3873.14 281 464777
customer reserve: removal 4279.27 104 513512
realization accept: apply 82.15 290 9858
realization accept: cancel 78.66 173 9439
payment from customer: creation 58.95 187 7074
payment from customer: removal 5.57 134 668
payment to supplier: creation 47.05 202 5646
payment to supplier: removal 3.45 169 414
service: total inventory turnovers 3.77 4517 452
service: total monetary turnovers 7.84 372 941
service: aggregate perf. data 0.89 16700 107
service: refresh index statistics 0.03 354467 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-01-2023 13:39:18 to 13-01-2023 13:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9217
TEST_TIME 2 6477
TEST_TIME 3 6358
TEST_TIME 4 6128
TEST_TIME 5 6014
TEST_TIME 6 5952
TEST_TIME 7 5878
TEST_TIME 8 5903
TEST_TIME 9 5964
TEST_TIME 10 6097
TEST_TIME 11 6408
TEST_TIME 12 6483
TEST_TIME 13 6561
TEST_TIME 14 6677
TEST_TIME 15 6738
TEST_TIME 16 6835
TEST_TIME 17 6905
TEST_TIME 18 7069
TEST_TIME 19 7090
TEST_TIME 20 7114
TEST_TIME 21 7170
TEST_TIME 22 7237
TEST_TIME 23 7585
TEST_TIME 24 7638
TEST_TIME 25 7847
TEST_TIME 26 7995
TEST_TIME 27 8226
TEST_TIME 28 8233
TEST_TIME 29 8120
TEST_TIME 30 8079
TEST_TIME 31 8421
TEST_TIME 32 8759
TEST_TIME 33 9191
TEST_TIME 34 9797
TEST_TIME 35 9945
TEST_TIME 36 10235
TEST_TIME 37 10280
TEST_TIME 38 10186
TEST_TIME 39 10448
TEST_TIME 40 10457
TEST_TIME 41 10361
TEST_TIME 42 10200
TEST_TIME 43 10214
TEST_TIME 44 10223
TEST_TIME 45 10182
TEST_TIME 46 10001
TEST_TIME 47 9856
TEST_TIME 48 9759
TEST_TIME 49 9656
TEST_TIME 50 9661
TEST_TIME 51 9610
TEST_TIME 52 9564
TEST_TIME 53 9589
TEST_TIME 54 9580
TEST_TIME 55 9560
TEST_TIME 56 9530
TEST_TIME 57 9462
TEST_TIME 58 9347
TEST_TIME 59 9600
TEST_TIME 60 9616
TEST_TIME 61 9604
TEST_TIME 62 9536
TEST_TIME 63 9533
TEST_TIME 64 9607
TEST_TIME 65 9595
TEST_TIME 66 9595
TEST_TIME 67 9578
TEST_TIME 68 9616
TEST_TIME 69 9674
TEST_TIME 70 9621
TEST_TIME 71 9534
TEST_TIME 72 9432
TEST_TIME 73 9527
TEST_TIME 74 9439
TEST_TIME 75 9396
TEST_TIME 76 9401
TEST_TIME 77 9335
TEST_TIME 78 9326
TEST_TIME 79 9274
TEST_TIME 80 9329
TEST_TIME 81 9289
TEST_TIME 82 9274
TEST_TIME 83 9270
TEST_TIME 84 9262
TEST_TIME 85 9271
TEST_TIME 86 9315
TEST_TIME 87 9342
TEST_TIME 88 9389
TEST_TIME 89 9391
TEST_TIME 90 9410
TEST_TIME 91 9326
TEST_TIME 92 9324
TEST_TIME 93 9247
TEST_TIME 94 9237
TEST_TIME 95 9204
TEST_TIME 96 9190
TEST_TIME 97 9153
TEST_TIME 98 9114
TEST_TIME 99 9037
TEST_TIME 100 9030
TEST_TIME 101 9056
TEST_TIME 102 9046
TEST_TIME 103 9022
TEST_TIME 104 9026
TEST_TIME 105 9005
TEST_TIME 106 9015
TEST_TIME 107 9000
TEST_TIME 108 8978
TEST_TIME 109 9035
TEST_TIME 110 9060
TEST_TIME 111 9080
TEST_TIME 112 9057
TEST_TIME 113 9047
TEST_TIME 114 9026
TEST_TIME 115 9000
TEST_TIME 116 8974
TEST_TIME 117 8989
TEST_TIME 118 8958
TEST_TIME 119 8946
TEST_TIME 120 8882

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-01-2023 13:39:22 to 13-01-2023 13:39:25.

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 1488802 1488802 0 0.00 0 47929 15 0 0
doc_list_biud 1488780 1488780 0 0.00 0 46984 42 0 0
sp_add_invoice_to_stock 5180 5044 136 2.62 49 227610 29266 0 136
sp_cancel_adding_invoice 5278 5278 0 0.00 6 102212 1303 0 0
sp_cancel_client_order 1813 1813 0 0.00 7 16709 459 0 0
sp_cancel_customer_reserve 513512 513512 0 0.00 1 46986 104 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 4 1985 135 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 4 5690 169 0 0
sp_cancel_supplier_invoice 5887 5875 12 0.20 2 19067 1090 0 12
sp_cancel_supplier_order 422 422 0 0.00 15 1587328 163251 0 0
sp_cancel_write_off 9439 9439 0 0.00 2 12088 173 0 0
sp_client_order 20032 20032 0 0.00 6 35129 490 0 0
sp_customer_reserve 464777 464777 0 0.00 0 105796 282 0 0
sp_fill_shopping_cart 30880 30880 0 0.00 1 34005 86 0 0
sp_fill_shopping_cart_clo_res 464732 464732 0 0.00 0 20489 21 0 0
sp_get_clo_for_invoice 4993 4993 0 0.00 0 22480 259 0 0
sp_kill_cost_storno 15799 15799 0 0.00 0 8455 82 0 0
sp_kill_qstorno_ret_qs2qd 1042760 1042760 0 0.00 0 46977 45 0 0
sp_kill_qty_storno 551185 551185 0 0.00 0 46983 105 0 0
sp_lock_selected_doc 20685 20685 0 0.00 0 10599 7 0 0
sp_make_cost_storno 27568 27568 0 0.00 0 11066 82 0 0
sp_multiply_rows_for_pdistr 27565 27565 0 0.00 0 9646 39 0 0
sp_multiply_rows_for_qdistr 20032 20032 0 0.00 0 13101 188 0 0
sp_pay_from_customer 7074 7074 0 0.00 6 15535 188 0 0
sp_pay_to_supplier 5646 5646 0 0.00 6 9913 202 0 0
sp_payment_common 12717 12717 0 0.00 2 14467 44 0 0
sp_qd_handle_on_cancel_clo 1813 1813 0 0.00 0 16685 382 0 0
sp_qd_handle_on_invoice_upd_sts 10267 10267 0 0.00 1 22154 553 0 0
sp_qd_handle_on_reserve_upd_sts 9857 9857 0 0.00 0 16851 70 0 0
sp_reserve_write_off 9858 9858 0 0.00 8 16885 290 0 0
sp_supplier_invoice 5465 5465 0 0.00 10 81462 3068 0 0
sp_supplier_order 5394 5394 0 0.00 7 52063 1490 0 0
srv_aggregate_perf_data 107 107 0 0.00 2581 138188 16700 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 731 127965 32987 0 0
srv_find_qd_qs_mism 1013107 1013107 0 0.00 0 47928 12 0 0
srv_make_invnt_saldo 452 452 0 0.00 51 115012 4517 0 0
srv_make_money_saldo 941 941 0 0.00 1 14189 373 0 0
srv_recalc_idx_stat 4 4 0 0.00 216805 514739 354467 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5050 5050 0 0.00 1 18295 1043 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 2 11040 583 0 0
t$perf-norm:sp_cancel_client_order 1813 1813 0 0.00 0 1945 32 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 994 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 2711 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 4337 32 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 743 29 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 121923 6998 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 2895 25 0 0
t$perf-norm:sp_client_order 20032 20032 0 0.00 0 7959 41 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 4687 20 0 0
t$perf-norm:sp_pay_from_customer 7074 7074 0 0.00 0 8940 24 0 0
t$perf-norm:sp_pay_to_supplier 5646 5646 0 0.00 0 4571 20 0 0
t$perf-norm:sp_reserve_write_off 9858 9858 0 0.00 0 8073 25 0 0
t$perf-norm:sp_supplier_invoice 5465 5465 0 0.00 0 5495 37 0 0
t$perf-norm:sp_supplier_order 5394 5394 0 0.00 0 5194 37 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 479 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 34 3 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 1639 20 0 0
t$perf-norm:srv_make_money_saldo 941 941 0 0.00 0 1132 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 192 51 0 0
v_add_invoice_to_stock 10246 9974 272 2.65 0 3806 28 0 272
v_all_customers 36737 36737 0 0.00 0 84 4 0 0
v_all_suppliers 29844 29844 0 0.00 0 91 5 0 0
v_all_wares 40058 40058 0 0.00 0 123 4 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 4050 24 0 0
v_cancel_client_order 3626 3626 0 0.00 0 2659 12 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 1681 13 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 3504 15 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 2391 20 0 0
v_cancel_supplier_invoice 856 832 24 2.80 0 1649 25 0 24
v_cancel_supplier_order 844 844 0 0.00 0 1653 18 0 0
v_cancel_write_off 1996 1996 0 0.00 0 2996 25 0 0
v_max_id_clo_ord 5393 5393 0 0.00 0 6857 7 0 0
v_max_id_clo_res 982 982 0 0.00 0 1534 12 0 0
v_max_id_ord_sup 5458 5458 0 0.00 0 3565 8 0 0
v_max_non_paid_invoice 1574 1574 0 0.00 0 200 8 0 0
v_max_non_paid_realizn 3760 3760 0 0.00 0 6723 10 0 0
v_min_id_clo_ord 5393 5393 0 0.00 0 1951 9 0 0
v_min_id_clo_res 982 982 0 0.00 0 318 8 0 0
v_min_id_ord_sup 5458 5458 0 0.00 0 9826 17 0 0
v_min_non_paid_invoice 5645 5645 0 0.00 0 4429 13 0 0
v_min_non_paid_realizn 7071 7071 0 0.00 0 7740 16 0 0
v_our_firm 9942 9942 0 0.00 0 59 5 0 0
v_reserve_write_off 19714 19714 0 0.00 0 4041 13 0 0
x_lock_depdocs_on_canc_invoice 10737 10737 0 0.00 0 10913 135 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 4004 338 0 0
x_make_qstorno_1000_1200 5394 5394 0 0.00 2 51967 1311 0 0
x_make_qstorno_1200_2000 5465 5465 0 0.00 5 77018 2834 0 0
x_make_qstorno_2100_3300 464777 464777 0 0.00 0 105785 247 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-01-2023 13:39:26 to 13-01-2023 13:39:30.

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:28 920 8192000000 8926685152 8981983232 11874576 0.144 4 2 3 0 12037920 101984 61360
11:08:31 37332 8192000000 9715460048 10014400512 790815760 8.803 100 93 186 0 822079440 7863968 23399712
11:10:17 8258 8192000000 9747867408 10053238784 827239312 9.171 100 85 170 0 859393824 11756448 20398064
11:11:36 5786 8192000000 9791337584 10106077184 864441328 9.545 100 88 176 0 902826480 17735776 20649376
11:12:48 13606 8192000000 9841791392 10175180800 902400288 9.922 100 76 152 0 959245584 39734112 17111184
11:14:07 9057 8192000000 9910625344 10255032320 954803552 10.438 100 87 174 0 1022981024 49625136 18552336
11:15:21 2319 8192000000 9950987008 10299826176 983474016 10.718 100 91 181 0 1065763824 64128144 18161664
11:16:26 1594 8192000000 9993143344 10350182400 1012072336 10.995 100 90 164 2 1104442816 75809616 16560864
11:17:31 1772 8192000000 10022265104 10390437888 1034669024 11.213 100 93 174 1 1136425296 83636928 18119344
11:18:36 1203 8192000000 10041646304 10418810880 1049945104 11.360 100 99 194 1 1165095680 95605472 19545104
11:19:38 1002 8192000000 10051054560 10433069056 1066196128 11.516 100 92 177 3 1174332288 89320480 18815680
11:20:42 11726 8192000000 10046206000 10436423680 1073323472 11.584 100 87 169 2 1181194368 90097952 17772944
11:21:55 1214 8192000000 10060280688 10440085504 1078401072 11.632 100 98 188 0 1180451984 83858720 18192192
11:22:57 1153 8192000000 10079872576 10465656832 1088379872 11.727 100 96 181 0 1198355472 90821392 19154208
11:24:01 1184 8192000000 10070438304 10455273472 1092083968 11.762 100 98 186 1 1187252560 75504176 19664416
11:25:03 1216 8192000000 10067145072 10457600000 1099368576 11.832 100 95 186 2 1186045072 67624736 19051760
11:26:06 1136 8192000000 10056064080 10446888960 1100514992 11.843 100 94 188 0 1179128592 58848288 19765312
11:27:11 1244 8192000000 10085671056 10476036096 1101632864 11.853 100 97 189 0 1206873904 85952528 19288512
11:28:14 1126 8192000000 10086088480 10478977024 1103339584 11.869 100 96 182 0 1208293216 86126432 18827200
11:29:17 1045 8192000000 10072395088 10461261824 1102302736 11.859 100 93 177 2 1194623920 73501312 18819872
11:30:20 1310 8192000000 10065961968 10454552576 1099720752 11.835 100 97 182 2 1186778192 69035184 18022256
11:31:24 1203 8192000000 10054386368 10447065088 1093675408 11.778 100 93 176 3 1172956416 59154816 20126192
11:32:27 1122 8192000000 10038400592 10423971840 1087215616 11.716 100 90 174 0 1164942608 61134528 16592464
11:33:29 1498 8192000000 10039646272 10423484416 1078882304 11.637 100 78 147 2 1155277616 61940672 14454640
11:34:31 1209 8192000000 10001621024 10382561280 1054364272 11.403 99 94 176 2 1122940688 48254720 20321696
11:35:34 1228 8192000000 9933676432 10300735488 1003698112 10.914 98 82 159 1 1055886992 35321664 16867216
11:36:37 4114 8192000000 10008619328 10383286272 1053930848 11.398 100 91 163 0 1130199680 59930960 16337872
11:37:44 1370 8192000000 10029405392 10407755776 1058741264 11.444 100 94 183 2 1148953616 70579824 19632528
11:39:10 3511 8192000000 10046196608 10428403712 1075667792 11.606 100 82 146 0 1168098016 78352000 14078224
11:40:20 1222 8192000000 10025430768 10404368384 1058469040 11.442 99 83 155 0 1144977920 69917312 16591568
11:41:28 6907 8192000000 10047292816 10433884160 1077251504 11.621 100 85 160 2 1164924976 71461408 16212064
11:42:40 1720 8192000000 10082238560 10464215040 1082615088 11.672 100 93 174 1 1196482848 95088912 18778848
11:43:46 15277 8192000000 10091594160 10488983552 1090285472 11.745 100 60 117 0 1212174560 109608864 12280224
11:45:10 1344 8192000000 10116627488 10497892352 1098677824 11.825 100 88 164 1 1225703344 110030224 16995296
11:46:16 2779 8192000000 10141817520 10533789696 1115521488 11.985 100 73 143 2 1264167984 132788144 15858352
11:47:22 5716 8192000000 10186257408 10589483008 1133894592 12.158 100 63 113 2 1309224672 162543648 12786432
11:48:36 830 8192000000 10207380416 10609569792 1158608176 12.390 100 98 191 1 1322515840 141179168 22728496
11:49:40 1782 8192000000 10238595136 10636718080 1161616576 12.418 100 91 172 2 1357058912 176806448 18635888
11:50:45 724 8192000000 10296536688 10690760704 1172071392 12.516 100 91 175 1 1405085520 214253552 18760576
11:51:48 1423 8192000000 10297410432 10707488768 1181356912 12.603 100 90 176 2 1413520624 211206288 20957424
11:52:52 1137 8192000000 10349052720 10754031616 1188181520 12.666 100 92 174 0 1455936688 248182384 19572784
11:53:55 1991 8192000000 10384109744 10801229824 1190052928 12.684 100 95 183 1 1496467616 285444256 20970432
11:55:02 1300 8192000000 10418754896 10834608128 1205703920 12.829 100 85 167 1 1533840320 309233680 18902720
11:56:07 1787 8192000000 10462374560 10876289024 1209696496 12.866 100 91 180 0 1567389744 336695552 20997696
11:57:14 1615 8192000000 10502097920 10918322176 1216406880 12.928 100 96 191 0 1610021344 370854608 22759856
11:58:22 2737 8192000000 10472807488 10901917696 1186658464 12.652 99 87 174 0 1578589328 370810832 21120032
11:59:28 1040 8192000000 10539274352 10960945152 1206073968 12.833 100 98 188 2 1642207712 413627712 22506032
12:00:33 977 8192000000 10525955840 10993946624 1195283040 12.733 100 97 191 2 1632511776 412094912 25133824
12:01:38 1381 8192000000 10534140544 10979094528 1186871152 12.654 100 97 191 3 1636145360 425930848 23343360
12:02:43 2067 8192000000 10550526832 11002183680 1189444256 12.678 100 90 180 1 1652683760 441005040 22234464
12:03:48 1353 8192000000 10520457632 10971152384 1187426128 12.659 100 74 142 1 1620702992 415953008 17323856
12:04:53 9793 8192000000 10527269376 10967572480 1182340800 12.612 100 59 116 1 1626628000 429279472 15007728
12:06:11 3094 8192000000 10531944960 10958540800 1179264976 12.583 100 58 114 0 1635737456 441830000 14642480
12:07:38 12158 8192000000 10539936112 11032346624 1163951040 12.440 100 39 76 0 1683820336 509185648 10683648
12:08:58 17013 8192000000 10512870496 11000311808 1165055936 12.451 100 52 105 0 1605213952 427532720 12625296
12:10:20 28861 8192000000 10336944624 10807992320 1148314800 12.294 99 33 65 0 1457277952 300889984 8073168
12:11:50 27620 8192000000 10222392848 10662998016 1131741056 12.138 99 31 58 1 1319931536 181046304 7144176
12:13:21 26903 8192000000 10162457952 10689777664 1142519104 12.239 100 22 42 0 1283699968 136127744 5053120
12:14:56 58925 8192000000 10134719216 10613096448 1121565280 12.042 98 15 30 0 1247404368 122252784 3586304
12:16:56 73995 8192000000 10057963344 10491936768 1113164688 11.962 98 17 33 0 1142693488 26431536 3097264
12:19:11 26841 8192000000 10111503088 10545127424 1130865232 12.130 99 39 72 1 1177515392 39587152 7063008
12:20:45 96734 8192000000 10083371504 10586976256 1146555680 12.277 100 12 24 0 1228290448 79311456 2423312
12:23:48 48428 8192000000 10089963264 10533142528 1117956144 12.008 97 41 74 1 1169049824 43090224 8003456
12:25:40 73125 8192000000 10119262944 10552143872 1152396544 12.332 100 50 87 2 1209106176 48200656 8508976
12:27:56 11911 8192000000 10150289008 10587447296 1149390656 12.304 99 47 90 0 1236750176 77907424 9452096
12:29:11 24518 8192000000 10163868128 10605043712 1134572064 12.164 98 33 66 0 1238387200 96292384 7522752
12:30:37 53668 8192000000 10172896096 10644164608 1162122448 12.423 100 47 88 2 1285817792 113733248 9962096
12:32:32 36026 8192000000 10157692800 10629017600 1152615296 12.334 99 14 28 0 1260290400 104616432 3058672
12:34:35 51870 8192000000 10195487008 10657517568 1143232496 12.246 99 12 24 0 1290720784 144377248 3111040
12:36:33 50188 8192000000 10090992112 10568699904 1136003552 12.178 99 15 30 0 1248351520 109134912 3213056
12:38:25 44295 8192000000 10085425056 10566672384 1146511552 12.277 100 29 57 0 1193193744 41506576 5175616
12:40:15 31024 8192000000 10187397200 10626551808 1156064368 12.366 100 54 108 0 1269652512 102477088 11111056
12:41:49 93418 8192000000 10071768544 10536812544 1098853024 11.827 96 15 30 0 1156798320 55182640 2762656
12:44:27 15759 8192000000 10128649456 10567344128 1134549856 12.164 99 42 84 0 1209275856 65627248 9098752
12:45:45 90240 8192000000 10087793792 10564370432 1119551568 12.023 98 21 43 0 1189347408 65839648 3956192
12:48:24 112047 8192000000 10144492464 10586894336 1117042544 11.999 98 29 56 0 1215782848 93385344 5354960
12:51:24 23368 8192000000 10107394912 10630221824 1133663216 12.156 100 16 32 0 1216008592 79096720 3248656
12:52:48 97218 8192000000 10087891920 10564902912 1131357392 12.134 99 17 34 0 1175986960 41637680 2991888
12:56:17 34136 8192000000 10117963344 10563117056 1134220400 12.161 100 22 44 0 1217879136 79249840 4408896
12:57:52 29319 8192000000 10090525472 10517618688 1127803200 12.101 99 58 106 1 1178934448 38071728 13059520
12:59:27 43181 8192000000 10115222528 10564546560 1107522448 11.909 97 23 46 0 1172422144 60199056 4700640
13:01:47 39961 8192000000 10146151104 10592952320 1134138352 12.160 99 25 50 0 1224342624 85117120 5087152
13:04:14 38295 8192000000 10134144096 10594791424 1111707680 11.949 97 30 58 0 1206656160 89046240 5902240
13:05:56 50610 8192000000 10080121968 10509651968 1095049664 11.791 96 37 76 0 1137896128 34732544 8113920
13:07:48 127963 8192000000 10043220416 10515791872 1078924768 11.637 96 27 53 0 1130431248 46254176 5252304
13:10:57 52323 8192000000 10078881856 10552864768 1120829776 12.035 99 30 60 0 1192531376 64909392 6792208
13:12:49 55708 8192000000 10055867152 10528681984 1120887696 12.035 99 20 41 0 1175433616 50040496 4505424
13:14:45 58451 8192000000 10074357456 10531721216 1128217312 12.105 99 26 51 1 1174013984 40272544 5524128
13:16:44 42048 8192000000 10106262304 10570014720 1130434384 12.125 99 24 48 0 1206009712 70680800 4894528
13:18:35 63989 8192000000 10078647744 10572738560 1146154624 12.273 100 13 26 0 1194723776 46262384 2306768
13:20:39 54723 8192000000 10073058720 10521882624 1140433328 12.220 99 24 49 1 1189060336 43583344 5043664
13:22:43 31862 8192000000 10160034272 10611290112 1160280272 12.406 100 36 72 0 1264973056 97233632 7459152
13:24:28 53256 8192000000 10155884512 10605301760 1142991424 12.244 99 47 94 0 1233440880 81090992 9358464
13:26:24 12961 8192000000 10093086048 10517417984 1123390544 12.059 99 54 105 0 1169457328 35600496 10466288
13:27:38 46355 8192000000 10112007520 10565029888 1115749280 11.987 98 42 84 0 1206914624 82669600 8495744
13:29:24 16205 8192000000 10108494144 10546470912 1142619008 12.240 100 44 87 0 1200569200 49328288 8621904
13:30:46 43684 8192000000 10112898720 10548011008 1090892624 11.751 95 34 67 0 1162877264 65250800 6733840
13:32:31 127901 8192000000 10146428064 10643451904 1119563328 12.023 99 34 69 0 1226079728 99465344 7051056
13:36:47 50737 8192000000 9473418720 9866788864 598936624 6.813 52 7 14 0 625725504 25280544 1508336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

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

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          215451
MON$OLDEST_ACTIVE               215452
MON$OLDEST_SNAPSHOT             215452
MON$NEXT_TRANSACTION            215452
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                       2059488
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 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 ---
:336: 
:337: mscs-test-161.budzdorov.ru    Fri Jan 13 13:37:43 2023
:338:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:339: 
:340: 
:341: mscs-test-161.budzdorov.ru    Fri Jan 13 13:37:43 2023
:342:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:343: 
:344: 
:345: mscs-test-161.budzdorov.ru    Fri Jan 13 13:37:43 2023
:346:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:347: 
:348: 
:349: mscs-test-161.budzdorov.ru    Fri Jan 13 13:37:43 2023
:350:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:351: 
:352: 
:353: mscs-test-161.budzdorov.ru    Fri Jan 13 13:37:43 2023
:354:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:355: 
:356: 
:357: mscs-test-161.budzdorov.ru    Fri Jan 13 13:37:43 2023
:358:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:359: 
:360: 
:361: mscs-test-161.budzdorov.ru    Fri Jan 13 13:37:43 2023
:362:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:363: 
:364: 
:365: mscs-test-161.budzdorov.ru    Fri Jan 13 13:37:43 2023
:366:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:367: 
:368: 
:369: mscs-test-161.budzdorov.ru    Fri Jan 13 13:37:43 2023
:370:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:371: 
--- end of diff output ---

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

13.01.23 13:39:53. 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-13 13:39:55 No severe PSQL-related problems occured
13.01.23 13:39:57. End of report.