Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.05.2022 19: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 16582580 kB
MemAvailable 31487196 kB
Buffers 0 kB
Cached 15103080 kB
SwapCached 3280 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.10
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 2022-05-04 19:38:19
DTS_END                         2022-05-04 19:38:19.0710
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/49500
TRN_ID                          243636
ATT_ID                          673
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: 9311.37 18871 1117364
customer order: creation 178.23 368 21388
customer order: refuse 14.88 366 1786
order to supplier: creation 45.78 1288 5494
order to supplier: removal 3.42 96146 410
invoice (draft): creation 47.10 3512 5652
invoice (draft): removal 53.98 751 6477
invoice accept: apply 42.14 17567 5057
invoice accept: cancel 47.77 772 5732
customer reserve: creation 3873.65 175 464838
customer reserve: removal 4707.24 54 564869
realization accept: apply 85.78 177 10294
realization accept: cancel 80.72 105 9686
payment from customer: creation 59.88 107 7185
payment from customer: removal 5.73 68 687
payment to supplier: creation 49.37 114 5924
payment to supplier: removal 3.38 70 406
service: total inventory turnovers 4.93 977 591
service: total monetary turnovers 6.51 28 781
service: aggregate perf. data 0.86 18256 103
service: refresh index statistics 0.03 236528 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-05-2022 19:39:23 to 04-05-2022 19:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3259
TEST_TIME 2 3587
TEST_TIME 3 3137
TEST_TIME 4 2869
TEST_TIME 5 3970
TEST_TIME 6 4949
TEST_TIME 7 4890
TEST_TIME 8 5143
TEST_TIME 9 5273
TEST_TIME 10 5859
TEST_TIME 11 5842
TEST_TIME 12 6016
TEST_TIME 13 6143
TEST_TIME 14 6530
TEST_TIME 15 6654
TEST_TIME 16 7031
TEST_TIME 17 7206
TEST_TIME 18 7643
TEST_TIME 19 8213
TEST_TIME 20 8330
TEST_TIME 21 8415
TEST_TIME 22 8729
TEST_TIME 23 8942
TEST_TIME 24 9060
TEST_TIME 25 9388
TEST_TIME 26 10140
TEST_TIME 27 10125
TEST_TIME 28 10031
TEST_TIME 29 9867
TEST_TIME 30 10108
TEST_TIME 31 10414
TEST_TIME 32 10228
TEST_TIME 33 9948
TEST_TIME 34 9919
TEST_TIME 35 9854
TEST_TIME 36 9781
TEST_TIME 37 9799
TEST_TIME 38 9661
TEST_TIME 39 9732
TEST_TIME 40 9804
TEST_TIME 41 9802
TEST_TIME 42 9689
TEST_TIME 43 9577
TEST_TIME 44 9504
TEST_TIME 45 9473
TEST_TIME 46 9558
TEST_TIME 47 9487
TEST_TIME 48 9527
TEST_TIME 49 9455
TEST_TIME 50 9461
TEST_TIME 51 9434
TEST_TIME 52 9451
TEST_TIME 53 9381
TEST_TIME 54 9582
TEST_TIME 55 9684
TEST_TIME 56 9648
TEST_TIME 57 9651
TEST_TIME 58 9765
TEST_TIME 59 9771
TEST_TIME 60 9702
TEST_TIME 61 9739
TEST_TIME 62 9623
TEST_TIME 63 9543
TEST_TIME 64 9516
TEST_TIME 65 9519
TEST_TIME 66 9463
TEST_TIME 67 9408
TEST_TIME 68 9375
TEST_TIME 69 9342
TEST_TIME 70 9372
TEST_TIME 71 9396
TEST_TIME 72 9381
TEST_TIME 73 9349
TEST_TIME 74 9352
TEST_TIME 75 9383
TEST_TIME 76 9385
TEST_TIME 77 9478
TEST_TIME 78 9515
TEST_TIME 79 9518
TEST_TIME 80 9582
TEST_TIME 81 9566
TEST_TIME 82 9578
TEST_TIME 83 9648
TEST_TIME 84 9619
TEST_TIME 85 9554
TEST_TIME 86 9497
TEST_TIME 87 9478
TEST_TIME 88 9513
TEST_TIME 89 9444
TEST_TIME 90 9422
TEST_TIME 91 9371
TEST_TIME 92 9328
TEST_TIME 93 9335
TEST_TIME 94 9341
TEST_TIME 95 9353
TEST_TIME 96 9435
TEST_TIME 97 9414
TEST_TIME 98 9473
TEST_TIME 99 9491
TEST_TIME 100 9466
TEST_TIME 101 9505
TEST_TIME 102 9510
TEST_TIME 103 9572
TEST_TIME 104 9570
TEST_TIME 105 9536
TEST_TIME 106 9497
TEST_TIME 107 9455
TEST_TIME 108 9442
TEST_TIME 109 9413
TEST_TIME 110 9400
TEST_TIME 111 9363
TEST_TIME 112 9328
TEST_TIME 113 9309
TEST_TIME 114 9243
TEST_TIME 115 9315
TEST_TIME 116 9316
TEST_TIME 117 9364
TEST_TIME 118 9337
TEST_TIME 119 9354
TEST_TIME 120 9313

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-05-2022 19:39:27 to 04-05-2022 19:39:30.

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 1548347 1548347 0 0.00 0 22885 6 0 0
doc_list_biud 1548345 1548345 0 0.00 0 29167 25 0 0
sp_add_invoice_to_stock 5184 5057 127 2.44 45 227413 17567 0 127
sp_cancel_adding_invoice 5732 5732 0 0.00 3 58351 772 0 0
sp_cancel_client_order 1786 1786 0 0.00 4 11230 367 0 0
sp_cancel_customer_reserve 564869 564869 0 0.00 1 24089 55 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 3555 69 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 2847 70 0 0
sp_cancel_supplier_invoice 6486 6477 9 0.13 1 29172 752 0 9
sp_cancel_supplier_order 410 410 0 0.00 6 956580 96146 0 0
sp_cancel_write_off 9686 9686 0 0.00 1 17212 105 0 0
sp_client_order 21388 21388 0 0.00 4 56679 369 0 0
sp_customer_reserve 464838 464838 0 0.00 0 106587 175 0 0
sp_fill_shopping_cart 32525 32525 0 0.00 1 18017 40 0 0
sp_fill_shopping_cart_clo_res 464829 464829 0 0.00 0 27692 15 0 0
sp_get_clo_for_invoice 5046 5046 0 0.00 0 22745 271 0 0
sp_kill_cost_storno 16511 16511 0 0.00 0 6539 52 0 0
sp_kill_qstorno_ret_qs2qd 1146311 1146311 0 0.00 0 29165 30 0 0
sp_kill_qty_storno 604300 604300 0 0.00 0 29167 64 0 0
sp_lock_selected_doc 21078 21078 0 0.00 0 6406 6 0 0
sp_make_cost_storno 28449 28449 0 0.00 0 17097 53 0 0
sp_multiply_rows_for_pdistr 28448 28448 0 0.00 0 17195 28 0 0
sp_multiply_rows_for_qdistr 21388 21388 0 0.00 0 56659 172 0 0
sp_pay_from_customer 7185 7185 0 0.00 4 13416 108 0 0
sp_pay_to_supplier 5924 5924 0 0.00 4 21962 114 0 0
sp_payment_common 13108 13108 0 0.00 1 11088 27 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 0 11223 341 0 0
sp_qd_handle_on_invoice_upd_sts 10778 10778 0 0.00 0 17335 305 0 0
sp_qd_handle_on_reserve_upd_sts 10294 10294 0 0.00 0 12758 65 0 0
sp_reserve_write_off 10294 10294 0 0.00 6 14988 178 0 0
sp_supplier_invoice 5652 5652 0 0.00 6 115929 3513 0 0
sp_supplier_order 5494 5494 0 0.00 5 31624 1288 0 0
srv_aggregate_perf_data 103 103 0 0.00 2945 83405 18256 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1675 120466 48383 0 0
srv_find_qd_qs_mism 1069228 1069228 0 0.00 0 22884 5 0 0
srv_make_invnt_saldo 591 591 0 0.00 54 24509 977 0 0
srv_make_money_saldo 781 781 0 0.00 0 3601 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 178021 274218 236528 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5058 5058 0 0.00 0 14364 195 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 1 4339 80 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 7115 18 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 16854 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 3018 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 200 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 4302 23 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 33693 1682 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 6839 12 0 0
t$perf-norm:sp_client_order 21388 21388 0 0.00 0 10239 17 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 21501 38 0 0
t$perf-norm:sp_pay_from_customer 7185 7185 0 0.00 0 8144 10 0 0
t$perf-norm:sp_pay_to_supplier 5924 5924 0 0.00 0 5576 11 0 0
t$perf-norm:sp_reserve_write_off 10294 10294 0 0.00 0 5687 11 0 0
t$perf-norm:sp_supplier_invoice 5652 5652 0 0.00 0 8065 18 0 0
t$perf-norm:sp_supplier_order 5494 5494 0 0.00 0 5598 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 387 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 28 1 0 0
t$perf-norm:srv_make_invnt_saldo 591 591 0 0.00 0 2573 33 0 0
t$perf-norm:srv_make_money_saldo 781 781 0 0.00 0 3202 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10346 10092 254 2.45 0 12872 19 0 254
v_all_customers 38768 38768 0 0.00 0 18 1 0 0
v_all_suppliers 30952 30952 0 0.00 0 22 1 0 0
v_all_wares 42774 42774 0 0.00 0 98 1 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 1584 13 0 0
v_cancel_client_order 3572 3572 0 0.00 0 2552 5 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 1071 4 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 3427 8 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 2391 8 0 0
v_cancel_supplier_invoice 832 814 18 2.16 0 3755 19 0 18
v_cancel_supplier_order 820 820 0 0.00 0 3801 12 0 0
v_cancel_write_off 1962 1962 0 0.00 0 3053 17 0 0
v_max_id_clo_ord 5494 5494 0 0.00 0 207 1 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 3224 17 0 0
v_max_id_ord_sup 5644 5644 0 0.00 0 6378 6 0 0
v_max_non_paid_invoice 1586 1586 0 0.00 0 542 3 0 0
v_max_non_paid_realizn 3969 3969 0 0.00 0 5851 6 0 0
v_min_id_clo_ord 5494 5494 0 0.00 0 3933 9 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 2442 8 0 0
v_min_id_ord_sup 5644 5644 0 0.00 0 8980 15 0 0
v_min_non_paid_invoice 5924 5924 0 0.00 0 5054 15 0 0
v_min_non_paid_realizn 7184 7184 0 0.00 0 6212 23 0 0
v_our_firm 10436 10436 0 0.00 0 14 1 0 0
v_reserve_write_off 20588 20588 0 0.00 0 4377 5 0 0
x_lock_depdocs_on_canc_invoice 11802 11802 0 0.00 0 21074 130 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 9185 366 0 0
x_make_qstorno_1000_1200 5494 5494 0 0.00 2 31617 1207 0 0
x_make_qstorno_1200_2000 5652 5652 0 0.00 2 115921 3368 0 0
x_make_qstorno_2100_3300 464838 464838 0 0.00 0 106583 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-05-2022 19:39:31 to 04-05-2022 19:39:35.

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:22 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:24 36951 8192000000 9904113408 10213363712 982484480 10.708 100 86 170 1 1010682384 5981520 22216384
17:11:09 21931 8192000000 9931601856 10250944512 1018260288 11.055 100 88 176 0 1049206016 8064256 22881472
17:12:35 20644 8192000000 9982564080 10313592832 1065043280 11.505 100 76 152 0 1092870816 8363584 19463952
17:14:14 9833 8192000000 10038912832 10391220224 1131412752 12.135 100 79 156 0 1159397776 9827088 18157936
17:15:27 6312 8192000000 10092785856 10452045824 1186339728 12.649 100 74 149 0 1214908320 11864944 16703648
17:16:35 5303 8192000000 10146123296 10514173952 1232127216 13.074 100 85 170 1 1265305168 14439200 18738752
17:17:42 3138 8192000000 10173954144 10552098816 1260818608 13.338 100 88 176 0 1293799792 12827808 20153376
17:18:47 1617 8192000000 10197781616 10582454272 1284869920 13.557 100 80 161 0 1314875904 12225424 17780560
17:19:51 9061 8192000000 10195851648 10594701312 1300255984 13.698 100 42 85 2 1316229520 5423232 10550304
17:21:01 4165 8192000000 10216355504 10620174336 1309445408 13.781 100 79 159 2 1342659952 14991408 18223136
17:22:09 4564 8192000000 10210662400 10624270336 1318514160 13.863 100 33 66 1 1332163872 6283904 7365808
17:23:15 8608 8192000000 10220046976 10636283904 1330576256 13.972 100 25 51 0 1342748480 6485120 5687104
17:24:24 52655 8192000000 10224739568 10648793088 1337789488 14.037 100 49 98 4 1355915264 7111408 11014368
17:26:18 5522 8192000000 10237850496 10659217408 1349820192 14.146 100 37 74 0 1365680624 7514336 8346096
17:27:26 5731 8192000000 10241492000 10659811328 1339648480 14.054 100 33 66 0 1354614384 7610864 7355040
17:28:32 14137 8192000000 10160244768 10557435904 1231724416 13.070 94 32 63 2 1245313408 5189136 8399856
17:29:51 17023 8192000000 10154933152 10559426560 1205721856 12.829 94 13 26 0 1214103920 5068064 3314000
17:31:12 5303 8192000000 10137608960 10536038400 1226449808 13.021 98 27 54 0 1238598816 6238432 5910576
17:32:19 8978 8192000000 10121458768 10514817024 1188575008 12.670 95 16 32 0 1197194272 5226096 3393168
17:33:30 13930 8192000000 10170527072 10579910656 1255561024 13.289 98 20 40 0 1270429408 9831360 5037024
17:34:46 36354 8192000000 10176875072 10592882688 1292100560 13.623 100 25 50 0 1303671232 5865024 5705648
17:36:26 5446 8192000000 10202271072 10614018048 1307850464 13.767 100 34 68 1 1321210112 5126464 8233184
17:37:33 11085 8192000000 10213882784 10630139904 1321227104 13.888 100 17 33 1 1331079008 5995664 3856240
17:38:50 19422 8192000000 10250103888 10665451520 1349404480 14.142 100 36 69 2 1367823552 10363168 8055904
17:40:17 34476 8192000000 10273578256 10693115904 1368292128 14.312 100 46 92 0 1392781904 13844000 10645776
17:41:53 48391 8192000000 10275854928 10703097856 1373366848 14.357 100 26 52 0 1391549552 10983744 7198960
17:43:47 8682 8192000000 10299478896 10717839360 1382846112 14.442 100 39 78 1 1405982368 12708512 10427744
17:44:58 11898 8192000000 10291724688 10724016128 1381321328 14.428 99 29 58 0 1400202064 11854464 7026272
17:46:14 4984 8192000000 10323276960 10738630656 1397655920 14.574 100 67 134 0 1431938912 18870176 15412816
17:47:24 4349 8192000000 10334268144 10757345280 1411002112 14.693 100 59 116 3 1444489840 20285856 13201872
17:48:36 4001 8192000000 10329248880 10749243392 1401658160 14.610 100 65 131 4 1434842784 17867952 15316672
17:49:54 5158 8192000000 10336627408 10757746688 1409764368 14.682 100 60 119 0 1445621632 21137456 14719808
17:51:05 1670 8192000000 10364478144 10782478336 1422649648 14.796 100 81 161 1 1468311504 25240624 20421232
17:52:09 5457 8192000000 10355204704 10763087872 1394469600 14.546 99 87 172 2 1450338064 34751728 21116736
17:53:19 3151 8192000000 10359317232 10767421440 1395679872 14.557 99 90 180 2 1453353120 35974240 21699008
17:54:28 3986 8192000000 10329765904 10730999808 1367629008 14.306 100 83 166 3 1427528896 39541760 20358128
17:55:40 2853 8192000000 10328424544 10737786880 1373476592 14.358 100 68 134 0 1431902256 41185040 17240624
17:56:47 3471 8192000000 10302736944 10702872576 1340912640 14.066 98 84 168 0 1404696496 42017984 21765872
17:57:54 14088 8192000000 10296261056 10717720576 1349918400 14.147 99 50 100 1 1399125808 35982400 13225008
17:59:09 3422 8192000000 10296630784 10710278144 1354733024 14.190 100 53 106 1 1403267744 34765424 13769296
18:00:16 18426 8192000000 10275516464 10697465856 1318327648 13.862 98 36 72 1 1369916288 41633312 9955328
18:02:01 20460 8192000000 10276094576 10703708160 1348297920 14.132 100 19 37 1 1382175376 28620912 5256544
18:03:44 48457 8192000000 10253160256 10700316672 1321270480 13.888 98 16 32 0 1354658688 29202720 4185488
18:05:34 36282 8192000000 10279006672 10721087488 1352407760 14.169 100 14 28 2 1378675600 22867520 3400320
18:07:11 96451 8192000000 10269577424 10711031808 1329810320 13.965 98 19 38 3 1365581408 31285952 4485136
18:09:48 15872 8192000000 10275880304 10698686464 1344844048 14.101 99 36 72 0 1363742992 11216864 7682080
18:11:13 58648 8192000000 10282773120 10709045248 1336667120 14.027 98 42 84 0 1353516256 6874528 9974608
18:13:16 108421 8192000000 10300870336 10739658752 1377736528 14.396 100 32 64 0 1399499280 14820448 6942304
18:16:12 40707 8192000000 10321998464 10759045120 1386827424 14.478 99 52 104 0 1419409712 20374320 12207968
18:18:10 42335 8192000000 10288675152 10759802880 1381393776 14.429 100 13 26 1 1407177200 22666480 3116944
18:19:53 69228 8192000000 10304038560 10749706240 1386536048 14.475 99 37 72 0 1403535184 8977792 8021344
18:23:23 59696 8192000000 10306241584 10765938688 1385552544 14.466 100 21 42 1 1416262816 25100576 5609696
18:25:27 51626 8192000000 10291636912 10754011136 1399461360 14.590 100 25 50 0 1414784864 9629728 5693776
18:27:24 79373 8192000000 10307450256 10765582336 1401628016 14.609 100 14 28 0 1412753792 7900336 3225440
18:29:44 76534 8192000000 10308922640 10767282176 1395283680 14.553 99 14 28 0 1412785440 14200448 3301312
18:32:04 66255 8192000000 10303410352 10766196736 1380529936 14.421 98 15 31 0 1400514208 15823936 4160336
18:34:12 95515 8192000000 10292965184 10745749504 1309298320 13.780 93 23 45 0 1328266784 13774032 5194432
18:36:53 71119 8192000000 10285481536 10740899840 1349942976 14.147 97 24 48 1 1367803952 12344992 5515984
18:39:10 28686 8192000000 10262260192 10702991360 1311993696 13.804 96 23 46 0 1325247280 8456848 4796736
18:40:39 83446 8192000000 10245318336 10691403776 1339658880 14.054 98 22 44 2 1353810544 9133488 5018176
18:43:03 50154 8192000000 10253145680 10711072768 1359829584 14.236 100 21 41 1 1374322272 9378464 5114224
18:46:38 57251 8192000000 10274890416 10729467904 1357026272 14.211 99 12 22 1 1373204784 13554720 2623792
18:48:39 53878 8192000000 10245792144 10716962816 1317352752 13.853 97 16 32 1 1339675824 18510784 3812288
18:50:51 43130 8192000000 10242371184 10682802176 1299751184 13.693 97 27 54 1 1314077216 8494144 5831888
18:52:43 59403 8192000000 10263057088 10708901888 1344239152 14.096 100 30 60 0 1364016800 12656352 7121296
18:54:48 64503 8192000000 10275635744 10712940544 1331849360 13.984 98 16 31 0 1350087472 14938336 3299776
18:56:53 90447 8192000000 10266605968 10698268672 1294247104 13.643 95 19 38 1 1313878128 15745504 3885520
18:59:29 56627 8192000000 10265997008 10718568448 1349842912 14.146 99 33 64 2 1366110144 9249712 7017520
19:01:30 120466 8192000000 10268032432 10733621248 1350119424 14.149 99 15 30 0 1373102816 19052624 3930768
19:04:35 31521 8192000000 10261933984 10712018944 1351109472 14.157 99 32 64 0 1367210176 8702928 7397776
19:06:07 80433 8192000000 10256931552 10723934208 1359205008 14.230 100 6 12 0 1375447264 14977600 1264656
19:08:59 47046 8192000000 10275288752 10734186496 1368721344 14.316 99 12 23 3 1376457936 5141200 2595392
19:11:32 54021 8192000000 10287660240 10750590976 1362457584 14.259 98 19 38 1 1383862432 16736608 4668240
19:13:39 104623 8192000000 10307556384 10758696960 1363445744 14.268 98 13 26 0 1380282688 14084160 2752784
19:16:27 81271 8192000000 10284706192 10744889344 1361195936 14.248 98 13 26 0 1376831728 12444880 3190912
19:18:50 65017 8192000000 10299267600 10736283648 1346550016 14.116 97 17 34 0 1362681632 12060864 4070752
19:20:55 109598 8192000000 10290163392 10747576320 1354069728 14.184 98 18 36 0 1375464880 17542912 3852240
19:23:56 76624 8192000000 10296720064 10742054912 1361850736 14.254 98 38 76 0 1383290160 12704256 8735168
19:26:36 35587 8192000000 10279197024 10738216960 1353214016 14.176 98 23 46 0 1374871104 17249344 4407744
19:28:12 62011 8192000000 10277447088 10737840128 1378892272 14.407 100 12 24 0 1391046608 9484960 2669376
19:30:59 55351 8192000000 10267784912 10722381824 1326670736 13.937 96 18 36 2 1346322960 15421120 4231104
19:32:58 81316 8192000000 10267290608 10713600000 1327298336 13.943 97 10 20 1 1345801904 16350880 2152688
19:35:22 61459 8192000000 10258604816 10710577152 1304317040 13.734 96 20 40 0 1317845072 9292656 4235376
19:37:27 62541 8192000000 8967969888 9157398528 128999264 1.550 10 1 2 0 132773856 3732240 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-05-2022 19:39:37 to 04-05-2022 19:39:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-05-2022 19:39:53 to 04-05-2022 19:39:55.

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          245911
MON$OLDEST_ACTIVE               245912
MON$OLDEST_SNAPSHOT             245912
MON$NEXT_TRANSACTION            245912
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                       2026400
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33585 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33585 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33585 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33585 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 ---
:43: 
:44: mscs-test-161.budzdorov.ru    Wed May  4 19:38:32 2022
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:46: 
:47: 
:48: mscs-test-161.budzdorov.ru    Wed May  4 19:38:33 2022
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:50: 
--- end of diff output ---

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

04.05.22 19:39:56. 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
2022-05-04 19:39:58 No severe PSQL-related problems occured
04.05.22 19:39:59. End of report.