Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.03.2023 22: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 15297780 kB
MemAvailable 30416564 kB
Buffers 0 kB
Cached 16393196 kB
SwapCached 3080 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-03-12 22:38:41
DTS_END                         2023-03-12 22:38:41.2660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48258
TRN_ID                          243623
ATT_ID                          714
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: 9335.37 20847 1120244
customer order: creation 172.92 383 20750
customer order: refuse 14.87 321 1784
order to supplier: creation 45.68 1207 5481
order to supplier: removal 3.60 95293 432
invoice (draft): creation 45.78 2995 5493
invoice (draft): removal 54.12 690 6494
invoice accept: apply 41.73 17287 5007
invoice accept: cancel 48.73 715 5847
customer reserve: creation 3850.54 171 462065
customer reserve: removal 4762.07 58 571448
realization accept: apply 83.38 199 10006
realization accept: cancel 82.62 107 9914
payment from customer: creation 58.80 120 7056
payment from customer: removal 5.78 90 694
payment to supplier: creation 49.44 143 5933
payment to supplier: removal 3.51 76 421
service: total inventory turnovers 4.88 1004 586
service: total monetary turnovers 6.22 28 746
service: aggregate perf. data 0.69 25391 83
service: refresh index statistics 0.03 270661 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-03-2023 22:39:54 to 12-03-2023 22:39:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2092
TEST_TIME 2 2298
TEST_TIME 3 2540
TEST_TIME 4 3571
TEST_TIME 5 3927
TEST_TIME 6 4030
TEST_TIME 7 3965
TEST_TIME 8 4307
TEST_TIME 9 4403
TEST_TIME 10 4687
TEST_TIME 11 5105
TEST_TIME 12 5202
TEST_TIME 13 5196
TEST_TIME 14 5416
TEST_TIME 15 5949
TEST_TIME 16 5837
TEST_TIME 17 6252
TEST_TIME 18 7285
TEST_TIME 19 7441
TEST_TIME 20 7514
TEST_TIME 21 7913
TEST_TIME 22 8670
TEST_TIME 23 8658
TEST_TIME 24 9216
TEST_TIME 25 9511
TEST_TIME 26 9749
TEST_TIME 27 10094
TEST_TIME 28 10318
TEST_TIME 29 10139
TEST_TIME 30 10249
TEST_TIME 31 10158
TEST_TIME 32 10027
TEST_TIME 33 9736
TEST_TIME 34 9808
TEST_TIME 35 9827
TEST_TIME 36 9671
TEST_TIME 37 9599
TEST_TIME 38 9608
TEST_TIME 39 9592
TEST_TIME 40 9560
TEST_TIME 41 9754
TEST_TIME 42 9708
TEST_TIME 43 9627
TEST_TIME 44 9680
TEST_TIME 45 9649
TEST_TIME 46 9561
TEST_TIME 47 9555
TEST_TIME 48 9469
TEST_TIME 49 9371
TEST_TIME 50 9205
TEST_TIME 51 9254
TEST_TIME 52 9228
TEST_TIME 53 9219
TEST_TIME 54 9301
TEST_TIME 55 9294
TEST_TIME 56 9334
TEST_TIME 57 9358
TEST_TIME 58 9401
TEST_TIME 59 9373
TEST_TIME 60 9465
TEST_TIME 61 9440
TEST_TIME 62 9619
TEST_TIME 63 9683
TEST_TIME 64 9636
TEST_TIME 65 9576
TEST_TIME 66 9528
TEST_TIME 67 9456
TEST_TIME 68 9435
TEST_TIME 69 9411
TEST_TIME 70 9375
TEST_TIME 71 9327
TEST_TIME 72 9298
TEST_TIME 73 9210
TEST_TIME 74 9288
TEST_TIME 75 9368
TEST_TIME 76 9324
TEST_TIME 77 9359
TEST_TIME 78 9331
TEST_TIME 79 9371
TEST_TIME 80 9359
TEST_TIME 81 9405
TEST_TIME 82 9442
TEST_TIME 83 9505
TEST_TIME 84 9477
TEST_TIME 85 9540
TEST_TIME 86 9654
TEST_TIME 87 9607
TEST_TIME 88 9504
TEST_TIME 89 9465
TEST_TIME 90 9475
TEST_TIME 91 9426
TEST_TIME 92 9394
TEST_TIME 93 9430
TEST_TIME 94 9381
TEST_TIME 95 9379
TEST_TIME 96 9397
TEST_TIME 97 9434
TEST_TIME 98 9402
TEST_TIME 99 9404
TEST_TIME 100 9441
TEST_TIME 101 9427
TEST_TIME 102 9431
TEST_TIME 103 9455
TEST_TIME 104 9518
TEST_TIME 105 9498
TEST_TIME 106 9476
TEST_TIME 107 9472
TEST_TIME 108 9390
TEST_TIME 109 9380
TEST_TIME 110 9341
TEST_TIME 111 9368
TEST_TIME 112 9331
TEST_TIME 113 9327
TEST_TIME 114 9265
TEST_TIME 115 9341
TEST_TIME 116 9341
TEST_TIME 117 9333
TEST_TIME 118 9355
TEST_TIME 119 9379
TEST_TIME 120 9338

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-03-2023 22:39:57 to 12-03-2023 22:40:01.

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 1548667 1548667 0 0.00 0 26047 6 0 0
doc_list_biud 1548666 1548666 0 0.00 0 33062 27 0 0
sp_add_invoice_to_stock 5179 5007 172 3.32 23 209688 17287 0 172
sp_cancel_adding_invoice 5847 5847 0 0.00 3 77016 715 0 0
sp_cancel_client_order 1784 1784 0 0.00 4 25085 322 0 0
sp_cancel_customer_reserve 571448 571448 0 0.00 0 33015 58 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 8065 90 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 2 2268 77 0 0
sp_cancel_supplier_invoice 6509 6494 15 0.23 1 24828 691 0 15
sp_cancel_supplier_order 432 432 0 0.00 6 1106371 95294 0 0
sp_cancel_write_off 9914 9914 0 0.00 1 16033 107 0 0
sp_client_order 20750 20750 0 0.00 3 26102 383 0 0
sp_customer_reserve 462065 462065 0 0.00 0 70075 172 0 0
sp_fill_shopping_cart 31724 31724 0 0.00 1 13323 36 0 0
sp_fill_shopping_cart_clo_res 462061 462061 0 0.00 0 16272 15 0 0
sp_get_clo_for_invoice 5002 5002 0 0.00 0 20619 298 0 0
sp_kill_cost_storno 16876 16876 0 0.00 0 11119 48 0 0
sp_kill_qstorno_ret_qs2qd 1159736 1159736 0 0.00 0 32974 31 0 0
sp_kill_qty_storno 610927 610927 0 0.00 0 33061 67 0 0
sp_lock_selected_doc 20868 20868 0 0.00 0 9103 7 0 0
sp_make_cost_storno 27997 27997 0 0.00 0 23356 58 0 0
sp_multiply_rows_for_pdistr 27997 27997 0 0.00 0 13417 28 0 0
sp_multiply_rows_for_qdistr 20750 20750 0 0.00 0 13835 183 0 0
sp_pay_from_customer 7056 7056 0 0.00 4 26051 120 0 0
sp_pay_to_supplier 5933 5933 0 0.00 4 26041 143 0 0
sp_payment_common 12989 12989 0 0.00 2 20069 30 0 0
sp_qd_handle_on_cancel_clo 1784 1784 0 0.00 0 25077 298 0 0
sp_qd_handle_on_invoice_upd_sts 10848 10848 0 0.00 0 33061 306 0 0
sp_qd_handle_on_reserve_upd_sts 10006 10006 0 0.00 0 21992 71 0 0
sp_reserve_write_off 10006 10006 0 0.00 6 23399 199 0 0
sp_supplier_invoice 5493 5493 0 0.00 5 82605 2995 0 0
sp_supplier_order 5481 5481 0 0.00 5 34257 1208 0 0
srv_aggregate_perf_data 83 83 0 0.00 3402 450703 25392 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1335 114573 43252 0 0
srv_find_qd_qs_mism 1072746 1072746 0 0.00 0 22833 5 0 0
srv_make_invnt_saldo 586 586 0 0.00 38 54775 1004 0 0
srv_make_money_saldo 746 746 0 0.00 1 5338 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 145680 410017 270662 0 0
t$perf-abend:sp_add_invoice_to_stock 172 172 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5007 5007 0 0.00 0 23523 156 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 11459 140 0 0
t$perf-norm:sp_cancel_client_order 1784 1784 0 0.00 0 3261 12 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 2886 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 1946 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 232 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 2136 17 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 38320 1625 0 0
t$perf-norm:sp_cancel_write_off 1017 1017 0 0.00 0 2636 11 0 0
t$perf-norm:sp_client_order 20750 20750 0 0.00 0 11530 17 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 3079 5 0 0
t$perf-norm:sp_pay_from_customer 7056 7056 0 0.00 0 5362 9 0 0
t$perf-norm:sp_pay_to_supplier 5933 5933 0 0.00 0 5809 11 0 0
t$perf-norm:sp_reserve_write_off 10006 10006 0 0.00 0 6982 12 0 0
t$perf-norm:sp_supplier_invoice 5493 5493 0 0.00 0 6172 17 0 0
t$perf-norm:sp_supplier_order 5481 5481 0 0.00 0 5793 15 0 0
t$perf-norm:srv_aggregate_perf_data 83 83 0 0.00 0 966 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 175 3 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 802 18 0 0
t$perf-norm:srv_make_money_saldo 746 746 0 0.00 0 1588 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 109 29 0 0
v_add_invoice_to_stock 10346 10002 344 3.32 0 8587 21 0 344
v_all_customers 37878 37878 0 0.00 0 87 0 0 0
v_all_suppliers 30408 30408 0 0.00 0 17 1 0 0
v_all_wares 41500 41500 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 1974 11 0 0
v_cancel_client_order 3568 3568 0 0.00 0 2025 4 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 2929 8 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 2182 7 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 2027 9 0 0
v_cancel_supplier_invoice 848 818 30 3.53 0 4553 22 0 30
v_cancel_supplier_order 864 864 0 0.00 0 1430 7 0 0
v_cancel_write_off 2034 2034 0 0.00 0 1743 13 0 0
v_max_id_clo_ord 5481 5481 0 0.00 0 1712 2 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 10809 29 0 0
v_max_id_ord_sup 5493 5493 0 0.00 0 7235 5 0 0
v_max_non_paid_invoice 1703 1703 0 0.00 0 5315 6 0 0
v_max_non_paid_realizn 3747 3747 0 0.00 0 1733 5 0 0
v_min_id_clo_ord 5481 5481 0 0.00 0 13241 14 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 3633 16 0 0
v_min_id_ord_sup 5493 5493 0 0.00 0 5640 5 0 0
v_min_non_paid_invoice 5933 5933 0 0.00 0 20064 26 0 0
v_min_non_paid_realizn 7056 7056 0 0.00 0 13402 17 0 0
v_our_firm 10240 10240 0 0.00 0 27 1 0 0
v_reserve_write_off 20012 20012 0 0.00 0 4367 8 0 0
x_lock_depdocs_on_canc_invoice 11932 11932 0 0.00 0 18526 126 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 29889 642 0 0
x_make_qstorno_1000_1200 5481 5481 0 0.00 2 34250 1131 0 0
x_make_qstorno_1200_2000 5493 5493 0 0.00 2 82570 2870 0 0
x_make_qstorno_2100_3300 462065 462065 0 0.00 0 70073 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-03-2023 22:40:02 to 12-03-2023 22:40:06.

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
20:08:44 3 8192000000 8801257040 8813379584 7414000 0.090 2 2 3 0 7555088 66144 74944
20:09:45 33141 8192000000 9903745616 10213650432 975361776 10.639 100 81 162 0 1002522272 5771392 21389104
20:11:23 14425 8192000000 9937783136 10258698240 1019675984 11.069 100 87 170 2 1050098672 8188560 22234128
20:12:45 18187 8192000000 9981139408 10312282112 1063898832 11.494 100 85 170 0 1094729760 9632560 21198368
20:14:05 10828 8192000000 10051610128 10397401088 1126518560 12.089 100 87 174 0 1158061264 10199424 21343280
20:15:22 5059 8192000000 10094438112 10453708800 1180103440 12.591 100 84 168 0 1211306576 12364320 18838816
20:16:31 3394 8192000000 10136172384 10504871936 1223165824 12.991 100 88 176 0 1256760976 14411360 19183792
20:17:37 1823 8192000000 10156519152 10527739904 1247442912 13.215 100 87 171 0 1279882512 13150208 19289392
20:18:41 1329 8192000000 10184479952 10566373376 1277865008 13.494 100 84 168 0 1308371216 11901840 18604368
20:19:43 19308 8192000000 10199987872 10590212096 1294786608 13.648 100 71 141 1 1321862096 11469152 15606336
20:21:03 16595 8192000000 10190625264 10598121472 1301364320 13.708 100 24 47 0 1311195392 5033360 4797712
20:22:21 6545 8192000000 10194100832 10606927872 1310615328 13.792 100 33 66 0 1324973808 6483552 7874928
20:23:29 6411 8192000000 10201359200 10617270272 1319078304 13.868 100 24 48 0 1329766352 5233920 5454128
20:24:38 10963 8192000000 10200395040 10622832640 1322870880 13.903 100 17 32 1 1332311280 5374896 4065504
20:25:51 21427 8192000000 10223636368 10643263488 1330838608 13.975 100 29 58 1 1343993776 6995600 6159568
20:27:14 8100 8192000000 10233609072 10643468288 1332598784 13.991 100 22 44 1 1342418992 5047344 4772864
20:28:28 11475 8192000000 10200248944 10616791040 1292137024 13.624 98 21 40 0 1307150048 10484656 4528368
20:29:42 47518 8192000000 10167567264 10576785408 1243383760 13.177 96 33 67 1 1257012768 5890320 7738688
20:31:35 6254 8192000000 10113541040 10506383360 1180686256 12.597 95 20 38 1 1191254432 6056192 4511984
20:32:42 6087 8192000000 10130482576 10528948224 1232390560 13.076 99 20 40 1 1242250928 5061072 4799296
20:33:48 6780 8192000000 10167705120 10576023552 1279532000 13.509 100 13 24 0 1287786208 5530176 2724032
20:34:55 31973 8192000000 10171661664 10582564864 1282713200 13.538 100 25 51 0 1293840960 5612000 5515760
20:36:30 4521 8192000000 10191209904 10602250240 1300205856 13.697 100 19 38 1 1310051408 5829888 4015664
20:37:35 3780 8192000000 10205375280 10613669888 1308915472 13.776 100 25 50 1 1320279008 4538944 6824592
20:38:41 4851 8192000000 10235496624 10646810624 1334762448 14.010 100 29 58 0 1345287104 4541568 5983088
20:39:51 39553 8192000000 10253727648 10665340928 1344908080 14.102 100 51 103 2 1363267824 7168480 11191264
20:41:36 36181 8192000000 10274946800 10689224704 1357758992 14.217 100 47 93 0 1383402544 15523984 10119568
20:43:16 16397 8192000000 10297536880 10717605888 1382041536 14.435 100 44 84 0 1411215648 19180000 9994112
20:44:38 13540 8192000000 10314360352 10733375488 1390426832 14.510 100 58 111 0 1428552064 24346208 13779024
20:46:02 5137 8192000000 10328681776 10746994688 1406374992 14.652 100 55 109 2 1439900864 20369680 13156192
20:47:11 9063 8192000000 10333913040 10759389184 1410988800 14.693 100 52 100 1 1445591968 22019200 12583968
20:48:24 17572 8192000000 10351923808 10773852160 1422225760 14.792 100 42 85 1 1457093584 24392480 10475344
20:49:54 13545 8192000000 10335540576 10755178496 1389130016 14.498 98 57 113 0 1426828960 23977072 13721872
20:51:14 3979 8192000000 10339703680 10755915776 1406901424 14.656 100 69 138 0 1451102016 27385312 16815280
20:52:28 1652 8192000000 10356238832 10761539584 1403569632 14.627 100 80 160 2 1460638864 36966144 20103088
20:53:34 3383 8192000000 10330980304 10746060800 1381348784 14.429 99 56 110 2 1430604256 34895760 14359712
20:54:52 9700 8192000000 10357098144 10773745664 1391523504 14.519 99 56 111 0 1452341440 45781952 15035984
20:56:15 1329 8192000000 10342448384 10747224064 1379998384 14.417 99 85 168 0 1448769440 47478752 21292304
20:57:23 1949 8192000000 10345442480 10753265664 1392772720 14.531 100 69 137 2 1445843408 35268912 17801776
20:58:27 14046 8192000000 10325439168 10752831488 1367775072 14.307 99 49 97 1 1427328656 46279296 13274288
20:59:48 17433 8192000000 10315341184 10736816128 1371612880 14.341 99 45 88 1 1425561504 42054096 11894528
21:01:09 16580 8192000000 10286516112 10720182272 1349911200 14.147 99 24 48 2 1389682688 33054672 6716816
21:02:31 27260 8192000000 10294321104 10736001024 1372371248 14.348 100 22 45 0 1405531840 27246160 5914432
21:03:59 38242 8192000000 10288854672 10744008704 1351116176 14.158 99 12 24 0 1385784960 31091744 3577040
21:05:40 45181 8192000000 10272177760 10723733504 1361710416 14.253 100 8 16 0 1383197136 20054016 1432704
21:07:41 79026 8192000000 10280472912 10723799040 1358661216 14.225 99 30 58 0 1375931168 11212896 6057056
21:10:09 107192 8192000000 10298693232 10733539328 1348972016 14.138 98 21 40 0 1361945264 8639984 4333264
21:13:31 108379 8192000000 10300604976 10746396672 1357157536 14.212 99 15 30 0 1371917904 12099216 2661152
21:16:20 21181 8192000000 10309226128 10742013952 1383939696 14.452 100 60 116 2 1415167104 17149360 14078048
21:17:50 37858 8192000000 10312469024 10765971456 1387889024 14.487 99 33 64 2 1412800896 17452704 7459168
21:19:28 114572 8192000000 10281471888 10744373248 1371552192 14.341 99 9 18 0 1393358464 19672416 2133856
21:22:23 40806 8192000000 10291403696 10761412608 1391568176 14.520 100 11 22 0 1407456912 13815248 2073488
21:24:04 41423 8192000000 10293099280 10742636544 1376855600 14.388 99 24 46 0 1391200224 9470368 4874256
21:25:48 35672 8192000000 10296755328 10757259264 1398712896 14.584 100 16 29 2 1407990384 6327024 2950464
21:27:27 104241 8192000000 10300180752 10758963200 1378224256 14.401 98 20 40 0 1392637824 10058768 4354800
21:30:17 63073 8192000000 10289265216 10762702848 1371961536 14.345 98 7 14 0 1387314368 13705696 1647136
21:32:21 41352 8192000000 10303636512 10763587584 1367773504 14.307 98 17 32 1 1380166464 8180272 4212688
21:34:05 33974 8192000000 10285424480 10747506688 1360298576 14.240 98 12 24 0 1375727808 12605120 2824112
21:35:40 28150 8192000000 10304953568 10757210112 1393550592 14.538 100 28 56 0 1411162160 11344000 6267568
21:37:08 26106 8192000000 10299932704 10745806848 1355264992 14.195 97 44 85 0 1381693520 16561456 9867072
21:38:37 87055 8192000000 10295634320 10749927424 1336022096 14.022 96 14 28 0 1360836864 21616336 3198432
21:41:07 25681 8192000000 10292762512 10738221056 1354618128 14.189 98 24 48 0 1369396944 9959872 4818944
21:42:52 59305 8192000000 10265222304 10711560192 1327352704 13.943 97 52 104 1 1360487264 20824576 12309984
21:44:52 61184 8192000000 10254256256 10703441920 1339655504 14.054 99 19 38 0 1353283648 9603104 4025040
21:47:09 57270 8192000000 10249089760 10707574784 1346437088 14.115 99 17 34 0 1360190512 10000432 3752992
21:49:26 46450 8192000000 10249759664 10699882496 1318626256 13.864 97 19 38 0 1331678720 8561616 4490848
21:51:13 90027 8192000000 10246772448 10696036352 1306725168 13.756 96 18 36 0 1326206096 15233024 4247904
21:53:46 25399 8192000000 10255429360 10694156288 1333931680 14.003 99 17 33 0 1346910352 9173008 3805664
21:55:12 38586 8192000000 10257223632 10703855616 1333073520 13.995 98 34 65 0 1349826592 9399440 7353632
21:56:53 18951 8192000000 10251290672 10697859072 1315746880 13.838 97 24 47 0 1330652944 9905488 5000576
21:58:16 24678 8192000000 10267192800 10704969728 1331866560 13.984 98 42 81 1 1351116736 9961072 9289104
21:59:45 23107 8192000000 10270257664 10712940544 1337836624 14.038 98 17 34 0 1351793440 10065488 3891328
22:01:09 95622 8192000000 10275795872 10726985728 1331858880 13.984 97 12 24 0 1351211696 16725616 2627200
22:03:45 95658 8192000000 10284663168 10757853184 1357075424 14.211 99 9 16 1 1375980672 16605056 2300192
22:06:21 52959 8192000000 10296352240 10741112832 1381860576 14.433 100 31 62 0 1400298576 11268320 7169680
22:08:45 41115 8192000000 10279149600 10744627200 1371410656 14.340 99 9 18 0 1384902144 11969088 1522400
22:10:27 74185 8192000000 10285108928 10746478592 1345800128 14.110 97 10 20 0 1357842752 9822432 2220192
22:12:44 57735 8192000000 10286980224 10737827840 1337994688 14.039 96 17 33 0 1351813072 10289904 3528480
22:14:42 57261 8192000000 10271904112 10720346112 1336245920 14.024 97 16 32 1 1354064288 14000832 3817536
22:16:46 33168 8192000000 10298380112 10742792192 1383911920 14.452 100 37 72 0 1402979424 10641568 8425936
22:18:20 86077 8192000000 10283157504 10737504256 1328423504 13.953 96 18 36 0 1342412224 9664736 4323984
22:20:47 34991 8192000000 10301038736 10739724288 1372259728 14.347 99 39 78 0 1391811120 10817024 8734368
22:22:27 65567 8192000000 10286495664 10738380800 1349637904 14.144 98 6 12 0 1366513984 15794160 1081920
22:24:33 29960 8192000000 10294552896 10735013888 1353453904 14.179 98 22 44 0 1367235712 9152128 4629680
22:26:05 75720 8192000000 10289184080 10739933184 1364937888 14.282 99 36 73 0 1385319392 12148320 8233184
22:28:31 35724 8192000000 10260467360 10712358912 1327450448 13.944 97 14 28 0 1348192208 17660272 3081488
22:30:18 56111 8192000000 10266926272 10728804352 1352632576 14.171 99 11 23 0 1367364240 12206912 2524752
22:32:14 78962 8192000000 10281450320 10743193600 1345413808 14.106 98 16 32 1 1366079152 16726400 3938944
22:34:34 47913 8192000000 10282696896 10730590208 1359439808 14.232 99 16 32 0 1379997520 16939168 3618544
22:36:29 58482 8192000000 10269586144 10717884416 1299530672 13.691 95 21 42 0 1316497264 11641168 5325424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-03-2023 22:40:08 to 12-03-2023 22:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 172 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-03-2023 22:40:24 to 12-03-2023 22:40:26.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          241817
MON$OLDEST_ACTIVE               244179
MON$OLDEST_SNAPSHOT             244179
MON$NEXT_TRANSACTION            244179
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-03 16:41:55.3470
MON$PAGES                       2026016
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:108: 
:109: mscs-test-161.budzdorov.ru    Sun Mar 12 22:38:49 2023
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:111: 
:112: 
:113: mscs-test-161.budzdorov.ru    Sun Mar 12 22:38:51 2023
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:115: 
:116: 
:117: mscs-test-161.budzdorov.ru    Sun Mar 12 22:38:53 2023
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:119: 
:120: 
:121: mscs-test-161.budzdorov.ru    Sun Mar 12 22:38:53 2023
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:123: 
--- end of diff output ---

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

12.03.23 22:40:27. There are 3 logs in /var/tmp/logs-oltp30 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-03-12 22:40:30 No severe PSQL-related problems occured
12.03.23 22:40:31. End of report.