Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.02.2023 19:40
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 15295376 kB
MemAvailable 30425892 kB
Buffers 0 kB
Cached 16437488 kB
SwapCached 3564 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-02-05 19:38:36
DTS_END                         2023-02-05 19:38:36.0300
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/44294
TRN_ID                          241681
ATT_ID                          703
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: 9308.77 17204 1117052
customer order: creation 181.43 334 21772
customer order: refuse 15.28 349 1834
order to supplier: creation 45.62 1235 5474
order to supplier: removal 3.58 82451 430
invoice (draft): creation 46.54 2943 5585
invoice (draft): removal 52.63 651 6316
invoice accept: apply 43.44 17202 5213
invoice accept: cancel 47.11 683 5653
customer reserve: creation 3959.33 174 475119
customer reserve: removal 4610.07 50 553208
realization accept: apply 87.08 171 10450
realization accept: cancel 83.85 96 10062
payment from customer: creation 60.05 106 7206
payment from customer: removal 5.73 86 687
payment to supplier: creation 50.92 120 6110
payment to supplier: removal 3.40 67 408
service: total inventory turnovers 5.14 920 617
service: total monetary turnovers 6.68 30 801
service: aggregate perf. data 0.86 20136 103
service: refresh index statistics 0.03 216275 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-02-2023 19:41:19 to 05-02-2023 19:41:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1637
TEST_TIME 2 2862
TEST_TIME 3 3324
TEST_TIME 4 3643
TEST_TIME 5 3757
TEST_TIME 6 3674
TEST_TIME 7 3946
TEST_TIME 8 4082
TEST_TIME 9 4410
TEST_TIME 10 4806
TEST_TIME 11 5258
TEST_TIME 12 5730
TEST_TIME 13 6431
TEST_TIME 14 6697
TEST_TIME 15 7264
TEST_TIME 16 7490
TEST_TIME 17 8157
TEST_TIME 18 7982
TEST_TIME 19 8231
TEST_TIME 20 8289
TEST_TIME 21 8452
TEST_TIME 22 8460
TEST_TIME 23 8935
TEST_TIME 24 9094
TEST_TIME 25 9793
TEST_TIME 26 9798
TEST_TIME 27 9784
TEST_TIME 28 9649
TEST_TIME 29 10277
TEST_TIME 30 10361
TEST_TIME 31 10179
TEST_TIME 32 10031
TEST_TIME 33 10200
TEST_TIME 34 10056
TEST_TIME 35 10186
TEST_TIME 36 10109
TEST_TIME 37 9923
TEST_TIME 38 10022
TEST_TIME 39 10101
TEST_TIME 40 10093
TEST_TIME 41 10047
TEST_TIME 42 10122
TEST_TIME 43 10026
TEST_TIME 44 9959
TEST_TIME 45 9740
TEST_TIME 46 9790
TEST_TIME 47 9803
TEST_TIME 48 9780
TEST_TIME 49 9692
TEST_TIME 50 9650
TEST_TIME 51 9707
TEST_TIME 52 9657
TEST_TIME 53 9596
TEST_TIME 54 9516
TEST_TIME 55 9447
TEST_TIME 56 9357
TEST_TIME 57 9257
TEST_TIME 58 9378
TEST_TIME 59 9504
TEST_TIME 60 9508
TEST_TIME 61 9526
TEST_TIME 62 9526
TEST_TIME 63 9632
TEST_TIME 64 9677
TEST_TIME 65 9733
TEST_TIME 66 9743
TEST_TIME 67 9727
TEST_TIME 68 9736
TEST_TIME 69 9788
TEST_TIME 70 9797
TEST_TIME 71 9776
TEST_TIME 72 9753
TEST_TIME 73 9654
TEST_TIME 74 9595
TEST_TIME 75 9612
TEST_TIME 76 9550
TEST_TIME 77 9569
TEST_TIME 78 9495
TEST_TIME 79 9441
TEST_TIME 80 9419
TEST_TIME 81 9406
TEST_TIME 82 9365
TEST_TIME 83 9398
TEST_TIME 84 9423
TEST_TIME 85 9444
TEST_TIME 86 9465
TEST_TIME 87 9474
TEST_TIME 88 9521
TEST_TIME 89 9556
TEST_TIME 90 9540
TEST_TIME 91 9539
TEST_TIME 92 9536
TEST_TIME 93 9549
TEST_TIME 94 9540
TEST_TIME 95 9545
TEST_TIME 96 9509
TEST_TIME 97 9528
TEST_TIME 98 9491
TEST_TIME 99 9457
TEST_TIME 100 9381
TEST_TIME 101 9389
TEST_TIME 102 9412
TEST_TIME 103 9378
TEST_TIME 104 9412
TEST_TIME 105 9397
TEST_TIME 106 9427
TEST_TIME 107 9422
TEST_TIME 108 9393
TEST_TIME 109 9410
TEST_TIME 110 9447
TEST_TIME 111 9498
TEST_TIME 112 9485
TEST_TIME 113 9466
TEST_TIME 114 9524
TEST_TIME 115 9521
TEST_TIME 116 9466
TEST_TIME 117 9435
TEST_TIME 118 9384
TEST_TIME 119 9377
TEST_TIME 120 9310

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-02-2023 19:41:23 to 05-02-2023 19:41:26.

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 1557710 1557710 0 0.00 0 22519 6 0 0
doc_list_biud 1557703 1557703 0 0.00 0 45286 23 0 0
sp_add_invoice_to_stock 5288 5213 75 1.41 43 182223 17202 0 75
sp_cancel_adding_invoice 5653 5653 0 0.00 4 48385 684 0 0
sp_cancel_client_order 1834 1834 0 0.00 3 16149 350 0 0
sp_cancel_customer_reserve 553208 553208 0 0.00 0 23488 50 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 2 12211 86 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 9553 67 0 0
sp_cancel_supplier_invoice 6323 6316 7 0.11 2 23793 652 0 7
sp_cancel_supplier_order 430 430 0 0.00 11 855269 82451 0 0
sp_cancel_write_off 10062 10062 0 0.00 1 22521 96 0 0
sp_client_order 21772 21772 0 0.00 3 22189 334 0 0
sp_customer_reserve 475119 475119 0 0.00 0 80503 175 0 0
sp_fill_shopping_cart 32811 32811 0 0.00 1 10408 31 0 0
sp_fill_shopping_cart_clo_res 475113 475113 0 0.00 0 16154 12 0 0
sp_get_clo_for_invoice 5207 5207 0 0.00 0 21155 247 0 0
sp_kill_cost_storno 16810 16810 0 0.00 0 22519 48 0 0
sp_kill_qstorno_ret_qs2qd 1123224 1123224 0 0.00 0 23774 27 0 0
sp_kill_qty_storno 593153 593153 0 0.00 0 45285 59 0 0
sp_lock_selected_doc 21691 21691 0 0.00 0 10869 7 0 0
sp_make_cost_storno 28973 28973 0 0.00 0 17398 49 0 0
sp_multiply_rows_for_pdistr 28970 28970 0 0.00 0 12997 28 0 0
sp_multiply_rows_for_qdistr 21772 21772 0 0.00 0 11192 142 0 0
sp_pay_from_customer 7206 7206 0 0.00 4 17811 107 0 0
sp_pay_to_supplier 6110 6110 0 0.00 5 20661 120 0 0
sp_payment_common 13316 13316 0 0.00 1 20650 30 0 0
sp_qd_handle_on_cancel_clo 1834 1834 0 0.00 0 16135 309 0 0
sp_qd_handle_on_invoice_upd_sts 10853 10853 0 0.00 0 45285 304 0 0
sp_qd_handle_on_reserve_upd_sts 10450 10450 0 0.00 0 15939 53 0 0
sp_reserve_write_off 10450 10450 0 0.00 6 21081 171 0 0
sp_supplier_invoice 5585 5585 0 0.00 6 90485 2943 0 0
sp_supplier_order 5474 5474 0 0.00 5 23776 1235 0 0
srv_aggregate_perf_data 103 103 0 0.00 2693 245624 20136 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2371 136661 47045 0 0
srv_find_qd_qs_mism 1068232 1068232 0 0.00 0 14994 6 0 0
srv_make_invnt_saldo 617 617 0 0.00 34 23655 920 0 0
srv_make_money_saldo 801 801 0 0.00 1 5304 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 151684 286610 216276 0 0
t$perf-abend:sp_add_invoice_to_stock 75 75 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5214 5214 0 0.00 0 40501 167 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 7087 88 0 0
t$perf-norm:sp_cancel_client_order 1834 1834 0 0.00 0 6146 15 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 1511 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 4846 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 1370 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 455 455 0 0.00 0 851 11 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 27200 1484 0 0
t$perf-norm:sp_cancel_write_off 1097 1097 0 0.00 0 2108 6 0 0
t$perf-norm:sp_client_order 21772 21772 0 0.00 0 12522 17 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 9552 25 0 0
t$perf-norm:sp_pay_from_customer 7206 7206 0 0.00 0 6197 12 0 0
t$perf-norm:sp_pay_to_supplier 6110 6110 0 0.00 0 10917 15 0 0
t$perf-norm:sp_reserve_write_off 10450 10450 0 0.00 0 16793 15 0 0
t$perf-norm:sp_supplier_invoice 5585 5585 0 0.00 0 9927 18 0 0
t$perf-norm:sp_supplier_order 5474 5474 0 0.00 0 4175 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 216 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 12 1 0 0
t$perf-norm:srv_make_invnt_saldo 617 617 0 0.00 0 807 14 0 0
t$perf-norm:srv_make_money_saldo 801 801 0 0.00 0 1318 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 13 5 0 0
v_add_invoice_to_stock 10544 10394 150 1.42 0 6383 20 0 150
v_all_customers 39712 39712 0 0.00 0 21 0 0 0
v_all_suppliers 30790 30790 0 0.00 0 32 1 0 0
v_all_wares 43544 43544 0 0.00 0 58 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 2607 8 0 0
v_cancel_client_order 3668 3668 0 0.00 0 6176 7 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 7634 9 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 842 4 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 686 5 0 0
v_cancel_supplier_invoice 924 910 14 1.51 0 4083 19 0 14
v_cancel_supplier_order 860 860 0 0.00 0 5207 13 0 0
v_cancel_write_off 2194 2194 0 0.00 0 2478 14 0 0
v_max_id_clo_ord 5469 5469 0 0.00 0 157 1 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 3450 10 0 0
v_max_id_ord_sup 5570 5570 0 0.00 0 3331 4 0 0
v_max_non_paid_invoice 1754 1754 0 0.00 0 9827 10 0 0
v_max_non_paid_realizn 3714 3714 0 0.00 0 5508 6 0 0
v_min_id_clo_ord 5469 5469 0 0.00 0 7757 8 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 5204 16 0 0
v_min_id_ord_sup 5570 5570 0 0.00 0 3655 5 0 0
v_min_non_paid_invoice 6110 6110 0 0.00 0 20646 24 0 0
v_min_non_paid_realizn 7206 7206 0 0.00 0 12310 19 0 0
v_our_firm 10816 10816 0 0.00 0 20 1 0 0
v_reserve_write_off 20898 20898 0 0.00 0 6514 6 0 0
x_lock_depdocs_on_canc_invoice 11514 11514 0 0.00 0 28692 110 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 9469 359 0 0
x_make_qstorno_1000_1200 5474 5474 0 0.00 1 23764 1166 0 0
x_make_qstorno_1200_2000 5585 5585 0 0.00 2 90470 2830 0 0
x_make_qstorno_2100_3300 475119 475119 0 0.00 0 73489 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-02-2023 19:41:27 to 05-02-2023 19:41:31.

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
17:08:40 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:40 25929 8192000000 9901268176 10212978688 986220032 10.745 100 86 172 0 1014407376 5978320 22209024
17:11:15 29714 8192000000 9947058480 10270515200 1031334000 11.181 100 87 174 0 1061299024 7811472 22153552
17:12:49 17067 8192000000 9974964048 10304475136 1061510656 11.471 100 84 168 0 1092155856 9377840 21267360
17:14:21 9324 8192000000 10049746928 10395529216 1135501200 12.173 100 85 170 0 1166515680 11217648 19796832
17:15:33 9497 8192000000 10078723920 10440724480 1166254480 12.462 100 75 150 0 1195825664 12004000 17567184
17:16:45 4449 8192000000 10122582896 10491342848 1211877264 12.886 100 81 161 0 1241687904 12263152 17547488
17:17:54 2417 8192000000 10142438896 10519048192 1239715040 13.144 100 73 146 0 1268871392 13398352 15758000
17:18:58 1905 8192000000 10181141216 10565050368 1271575136 13.436 100 76 152 1 1301223120 13374704 16273280
17:20:01 1401 8192000000 10201219952 10582822912 1285624112 13.564 100 86 175 0 1317480832 12875760 18980960
17:21:03 911 8192000000 10209334128 10598166528 1292798368 13.630 100 89 178 2 1326057440 13137504 20121568
17:22:07 5046 8192000000 10193246816 10600603648 1298512656 13.682 100 38 76 0 1314521456 7947392 8061408
17:23:15 8500 8192000000 10192386720 10606829568 1304916240 13.740 100 16 32 0 1313871872 5411536 3544096
17:24:24 9636 8192000000 10198805376 10611531776 1311931616 13.804 100 31 60 1 1327092432 6476400 8684416
17:25:35 23028 8192000000 10211211344 10624921600 1313702720 13.820 100 40 75 3 1331440192 8764608 8972864
17:26:58 10825 8192000000 10210529088 10633469952 1328046176 13.949 100 17 34 0 1337859024 5504400 4308448
17:28:11 11077 8192000000 10221790160 10640846848 1327817488 13.947 100 21 40 1 1337964544 5467840 4679216
17:29:23 52623 8192000000 10199810432 10614239232 1306515152 13.754 100 27 55 0 1319166544 5965568 6685824
17:31:18 5228 8192000000 10145523824 10542628864 1223948672 12.998 97 35 70 0 1238147184 6414560 7783952
17:32:25 6372 8192000000 10116151856 10514145280 1210771648 12.876 97 24 47 0 1222039456 5868752 5399056
17:33:34 10084 8192000000 10149178240 10551844864 1255411936 13.288 100 29 58 0 1267699056 5651904 6635216
17:34:45 64385 8192000000 10159661984 10568806400 1260491312 13.335 99 28 56 0 1272857376 5689008 6677056
17:36:50 10132 8192000000 10182975424 10592550912 1291314288 13.616 100 24 46 0 1302212336 5354016 5544032
17:38:02 18458 8192000000 10205966576 10617659392 1309461904 13.781 100 17 34 0 1317670960 4805168 3403888
17:39:29 12908 8192000000 10236364912 10641330176 1329536480 13.963 100 45 86 0 1349313920 10640432 9137008
17:40:46 83047 8192000000 10260576048 10678697984 1343609856 14.090 100 35 70 0 1364932304 13279136 8043312
17:43:35 13406 8192000000 10276428832 10697084928 1366598688 14.297 100 38 76 0 1390937088 15740368 8598032
17:44:55 11619 8192000000 10296826864 10714107904 1380739744 14.423 100 51 100 0 1411978448 18782176 12456528
17:46:12 6134 8192000000 10308886016 10728374272 1386006816 14.470 100 50 99 1 1419950240 21380912 12562512
17:47:24 7947 8192000000 10325129408 10748194816 1398157856 14.579 100 56 109 0 1438428816 26900816 13370144
17:48:38 6981 8192000000 10318262544 10735947776 1387985952 14.488 99 59 118 1 1428928080 26556288 14385840
17:49:48 7380 8192000000 10337108672 10759442432 1399225984 14.588 100 72 143 2 1447973952 30650464 18097504
17:51:04 2371 8192000000 10341227728 10765574144 1407468928 14.661 100 53 106 1 1454447872 33881936 13097008
17:52:10 2701 8192000000 10355102784 10774519808 1410031312 14.684 100 77 155 1 1462433296 34490768 17911216
17:53:21 2962 8192000000 10340839584 10759622656 1379787872 14.415 99 76 149 0 1437819536 39605840 18425824
17:54:27 11789 8192000000 10325310336 10757816320 1390776864 14.513 100 47 92 0 1434441936 32200752 11464320
17:55:46 7109 8192000000 10304879440 10738360320 1375441056 14.376 99 32 64 0 1406914800 23743728 7730016
17:57:00 2616 8192000000 10356699472 10771116032 1408290256 14.669 100 70 140 1 1454513168 29357632 16865280
17:58:06 20202 8192000000 10316896976 10753904640 1385715376 14.468 99 24 46 0 1421147968 28973840 6458752
17:59:37 20507 8192000000 10286313824 10722148352 1349473328 14.143 99 24 48 0 1389189520 32946768 6769424
18:01:01 5466 8192000000 10316902560 10731606016 1369518448 14.323 100 66 128 1 1420697200 35284496 15894256
18:02:12 12473 8192000000 10311664112 10733559808 1362312320 14.258 99 36 72 0 1407030560 34793712 9924528
18:03:44 20968 8192000000 10287524208 10721796096 1352526976 14.170 99 30 60 1 1397708640 36943232 8238432
18:05:06 31918 8192000000 10307329904 10732621824 1358622976 14.225 99 50 98 5 1391746336 21343376 11779984
18:06:39 106703 8192000000 10267085872 10727985152 1341407616 14.070 98 22 42 1 1374418752 27489792 5521344
18:09:26 25304 8192000000 10295264928 10721169408 1346235504 14.114 98 34 69 1 1366902448 12996160 7670784
18:10:59 44281 8192000000 10264484400 10705629184 1340494240 14.062 98 29 58 1 1363726560 16224912 7007408
18:12:51 128904 8192000000 10278453952 10725064704 1350605696 14.153 99 9 18 1 1365040560 12327056 2107808
18:16:00 33345 8192000000 10277565920 10711957504 1353584400 14.180 99 38 76 0 1374095952 11560960 8950592
18:17:34 126267 8192000000 10278733712 10724032512 1304399232 13.735 95 7 14 1 1321759520 15501808 1858480
18:20:56 29976 8192000000 10311115504 10740633600 1374809632 14.370 100 44 82 1 1392325760 7210736 10305392
18:22:28 136660 8192000000 10307360560 10760912896 1383947936 14.452 100 24 48 0 1412106208 21813616 6344656
18:28:05 44090 8192000000 10292088688 10752053248 1368351392 14.312 98 39 75 1 1391596624 13837680 9407552
18:29:55 56526 8192000000 10295967168 10760241152 1388629888 14.494 100 9 18 0 1403005840 12126864 2249088
18:32:02 36570 8192000000 10289744496 10751639552 1377689152 14.396 99 16 32 0 1392393568 10884064 3820352
18:33:39 66433 8192000000 10330647824 10789953536 1408949344 14.675 100 24 48 0 1431428960 16381792 6097824
18:36:14 78710 8192000000 10303558256 10773737472 1353777952 14.181 96 10 20 0 1382377184 25608016 2991216
18:38:38 90247 8192000000 10301392528 10763956224 1343030736 14.085 96 25 50 0 1360673328 12133264 5509328
18:41:08 53104 8192000000 10271692112 10726719488 1307078720 13.760 94 14 27 1 1323193024 12895184 3219120
18:43:02 91020 8192000000 10265056768 10725044224 1309693184 13.783 95 13 26 0 1328273360 15511072 3069104
18:45:33 51012 8192000000 10286309264 10717900800 1345610864 14.108 98 33 66 0 1367296304 14329408 7356032
18:47:25 64386 8192000000 10274246880 10712088576 1334310464 14.006 98 26 52 1 1351991696 12442272 5238960
18:49:56 53810 8192000000 10276832048 10715369472 1338151184 14.041 98 37 75 1 1356259408 9333264 8774960
18:51:50 47684 8192000000 10268752768 10718064640 1353256112 14.177 99 38 73 0 1368735312 7162448 8316752
18:53:43 60117 8192000000 10276390320 10725789696 1350737312 14.154 98 23 46 0 1365850784 9427536 5685936
18:55:45 43935 8192000000 10249901392 10711732224 1328729888 13.956 97 10 20 0 1341739504 10304352 2705264
18:57:29 89049 8192000000 10279497664 10727825408 1353478416 14.179 99 13 26 0 1367523008 10770192 3274400
19:00:00 46117 8192000000 10279423168 10739109888 1355962352 14.201 99 13 26 0 1372439520 13392112 3085056
19:01:50 35937 8192000000 10279131792 10725654528 1324507856 13.918 96 22 44 0 1340397776 11137072 4752848
19:03:32 94545 8192000000 10265782496 10718924800 1305001936 13.741 95 14 28 0 1321052208 13147008 2903264
19:06:13 73975 8192000000 10271317216 10733842432 1334264112 14.006 97 18 36 0 1353322672 14700960 4357600
19:08:28 23355 8192000000 10292263872 10734080000 1364505280 14.278 99 24 48 0 1377968816 8524096 4939440
19:09:52 17897 8192000000 10298300432 10734559232 1364188624 14.275 99 51 103 0 1384539648 9209696 11141328
19:11:15 23767 8192000000 10285111952 10730668032 1344674800 14.100 97 25 51 0 1357964608 7606160 5683648
19:12:47 10241 8192000000 10299971632 10738089984 1373504208 14.358 99 44 89 2 1390810784 7490000 9816576
19:14:07 67776 8192000000 10292518080 10752331776 1382028624 14.435 100 19 38 0 1397177520 10663280 4485616
19:16:25 35266 8192000000 10289707024 10762104832 1387547456 14.484 100 17 33 0 1404684848 13469792 3667600
19:18:03 119567 8192000000 10289764128 10765524992 1373496176 14.358 98 10 20 0 1390139136 14200256 2442704
19:21:07 65774 8192000000 10296362208 10767446016 1381205344 14.427 99 11 22 0 1399934416 15816976 2912096
19:23:16 79233 8192000000 10271893424 10735230976 1341397520 14.070 97 11 22 0 1355031280 11260048 2373712
19:25:37 40929 8192000000 10270494576 10724003840 1337917040 14.039 97 20 40 1 1350695616 8077024 4701552
19:27:19 39602 8192000000 10272445648 10717630464 1325376576 13.925 97 19 38 0 1340988064 11401344 4210144
19:29:03 117807 8192000000 10269418096 10720272384 1290049696 13.605 94 12 24 0 1311809408 19430960 2328752
19:32:03 56010 8192000000 10279479008 10734194688 1339375472 14.052 98 33 66 1 1361577088 15230608 6971008
19:33:59 108960 8192000000 10247372736 10695045120 1291460240 13.618 94 16 32 0 1302238288 7522624 3255424
19:36:51 37687 8192000000 10277604048 10720296960 1356802992 14.209 99 29 58 1 1374197568 10409840 6984736
19:38:32 20580 8192000000 9773309056 10155192320 901258704 9.911 66 4 8 0 916229392 14279792 690896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-02-2023 19:41:33 to 05-02-2023 19:41:48.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-02-2023 19:41:49 to 05-02-2023 19:41:51.

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          241680
MON$OLDEST_ACTIVE               242629
MON$OLDEST_SNAPSHOT             242629
MON$NEXT_TRANSACTION            242629
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                       2032864
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.33659 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33659 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33659 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33659 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 ---
:67: 
:68: mscs-test-161.budzdorov.ru    Sun Feb  5 19:39:17 2023
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:70: 
--- end of diff output ---

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

05.02.23 19:41:52. 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-02-05 19:41:55 No severe PSQL-related problems occured
05.02.23 19:41:56. End of report.