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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-29 13:37:23
DTS_END                         2022-12-29 13:37:23.4020
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(68:17)
IP                              ::1/36820
TRN_ID                          212546
ATT_ID                          622
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: 9107.09 25703 1092851
customer order: creation 169.33 462 20320
customer order: refuse 14.16 485 1699
order to supplier: creation 44.13 1392 5295
order to supplier: removal 3.51 189177 421
invoice (draft): creation 45.92 3180 5510
invoice (draft): removal 52.95 1053 6354
invoice accept: apply 40.45 28172 4854
invoice accept: cancel 47.42 1297 5690
customer reserve: creation 3786.28 264 454353
customer reserve: removal 4614.21 112 553705
realization accept: apply 80.49 313 9659
realization accept: cancel 83.30 184 9996
payment from customer: creation 56.78 189 6813
payment from customer: removal 5.77 148 692
payment to supplier: creation 46.61 202 5593
payment to supplier: removal 3.17 183 380
service: total inventory turnovers 3.82 4721 458
service: total monetary turnovers 7.82 399 938
service: aggregate perf. data 0.98 15951 117
service: refresh index statistics 0.03 266175 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-12-2022 13:38:12 to 29-12-2022 13:38:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9666
TEST_TIME 2 5593
TEST_TIME 3 5586
TEST_TIME 4 5322
TEST_TIME 5 5404
TEST_TIME 6 5553
TEST_TIME 7 5631
TEST_TIME 8 5713
TEST_TIME 9 5797
TEST_TIME 10 5731
TEST_TIME 11 6032
TEST_TIME 12 6259
TEST_TIME 13 6595
TEST_TIME 14 6581
TEST_TIME 15 6619
TEST_TIME 16 6827
TEST_TIME 17 6789
TEST_TIME 18 6878
TEST_TIME 19 6832
TEST_TIME 20 6928
TEST_TIME 21 7045
TEST_TIME 22 7269
TEST_TIME 23 7355
TEST_TIME 24 7320
TEST_TIME 25 7440
TEST_TIME 26 7712
TEST_TIME 27 7831
TEST_TIME 28 7838
TEST_TIME 29 8129
TEST_TIME 30 8163
TEST_TIME 31 8655
TEST_TIME 32 8626
TEST_TIME 33 8989
TEST_TIME 34 9113
TEST_TIME 35 9689
TEST_TIME 36 10002
TEST_TIME 37 10175
TEST_TIME 38 10555
TEST_TIME 39 10411
TEST_TIME 40 10472
TEST_TIME 41 10643
TEST_TIME 42 10479
TEST_TIME 43 10360
TEST_TIME 44 10192
TEST_TIME 45 10251
TEST_TIME 46 10106
TEST_TIME 47 10047
TEST_TIME 48 10051
TEST_TIME 49 10006
TEST_TIME 50 9845
TEST_TIME 51 9723
TEST_TIME 52 9699
TEST_TIME 53 9676
TEST_TIME 54 9664
TEST_TIME 55 9651
TEST_TIME 56 9580
TEST_TIME 57 9495
TEST_TIME 58 9442
TEST_TIME 59 9391
TEST_TIME 60 9599
TEST_TIME 61 9661
TEST_TIME 62 9708
TEST_TIME 63 9694
TEST_TIME 64 9668
TEST_TIME 65 9625
TEST_TIME 66 9690
TEST_TIME 67 9650
TEST_TIME 68 9653
TEST_TIME 69 9696
TEST_TIME 70 9711
TEST_TIME 71 9843
TEST_TIME 72 9794
TEST_TIME 73 9732
TEST_TIME 74 9692
TEST_TIME 75 9598
TEST_TIME 76 9555
TEST_TIME 77 9535
TEST_TIME 78 9518
TEST_TIME 79 9531
TEST_TIME 80 9484
TEST_TIME 81 9431
TEST_TIME 82 9400
TEST_TIME 83 9328
TEST_TIME 84 9268
TEST_TIME 85 9266
TEST_TIME 86 9235
TEST_TIME 87 9283
TEST_TIME 88 9252
TEST_TIME 89 9220
TEST_TIME 90 9261
TEST_TIME 91 9262
TEST_TIME 92 9248
TEST_TIME 93 9277
TEST_TIME 94 9340
TEST_TIME 95 9311
TEST_TIME 96 9276
TEST_TIME 97 9338
TEST_TIME 98 9302
TEST_TIME 99 9370
TEST_TIME 100 9402
TEST_TIME 101 9396
TEST_TIME 102 9344
TEST_TIME 103 9281
TEST_TIME 104 9221
TEST_TIME 105 9250
TEST_TIME 106 9215
TEST_TIME 107 9209
TEST_TIME 108 9179
TEST_TIME 109 9131
TEST_TIME 110 9157
TEST_TIME 111 9129
TEST_TIME 112 9105
TEST_TIME 113 9119
TEST_TIME 114 9147
TEST_TIME 115 9128
TEST_TIME 116 9121
TEST_TIME 117 9184
TEST_TIME 118 9174
TEST_TIME 119 9188
TEST_TIME 120 9131

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-12-2022 13:38:15 to 29-12-2022 13:38:19.

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 1512682 1512682 0 0.00 0 18906 15 0 0
doc_list_biud 1512671 1512671 0 0.00 0 41022 47 0 0
sp_add_invoice_to_stock 4935 4854 81 1.64 46 224033 28172 0 81
sp_cancel_adding_invoice 5690 5690 0 0.00 7 63003 1297 0 0
sp_cancel_client_order 1699 1699 0 0.00 6 11289 485 0 0
sp_cancel_customer_reserve 553705 553705 0 0.00 1 28938 113 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 3 5154 148 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 4 9202 184 0 0
sp_cancel_supplier_invoice 6360 6354 6 0.09 3 41033 1053 0 6
sp_cancel_supplier_order 421 421 0 0.00 9 1752315 189177 0 0
sp_cancel_write_off 9996 9996 0 0.00 2 16770 185 0 0
sp_client_order 20320 20320 0 0.00 5 14885 463 0 0
sp_customer_reserve 454353 454353 0 0.00 0 40606 264 0 0
sp_fill_shopping_cart 31107 31107 0 0.00 1 25181 85 0 0
sp_fill_shopping_cart_clo_res 454330 454330 0 0.00 0 13993 18 0 0
sp_get_clo_for_invoice 4821 4821 0 0.00 0 21865 263 0 0
sp_kill_cost_storno 16758 16758 0 0.00 0 16759 83 0 0
sp_kill_qstorno_ret_qs2qd 1124181 1124181 0 0.00 0 41018 49 0 0
sp_kill_qty_storno 592339 592339 0 0.00 0 41021 112 0 0
sp_lock_selected_doc 20134 20134 0 0.00 0 9175 9 0 0
sp_make_cost_storno 26885 26885 0 0.00 0 16191 88 0 0
sp_multiply_rows_for_pdistr 26883 26883 0 0.00 0 7831 42 0 0
sp_multiply_rows_for_qdistr 20320 20320 0 0.00 0 10844 174 0 0
sp_pay_from_customer 6813 6813 0 0.00 5 20536 190 0 0
sp_pay_to_supplier 5593 5593 0 0.00 7 12540 203 0 0
sp_payment_common 12405 12405 0 0.00 2 8305 49 0 0
sp_qd_handle_on_cancel_clo 1699 1699 0 0.00 0 11279 405 0 0
sp_qd_handle_on_invoice_upd_sts 10509 10509 0 0.00 0 17163 573 0 0
sp_qd_handle_on_reserve_upd_sts 9655 9655 0 0.00 0 18354 72 0 0
sp_reserve_write_off 9659 9659 0 0.00 8 18380 313 0 0
sp_supplier_invoice 5510 5510 0 0.00 8 102747 3180 0 0
sp_supplier_order 5295 5295 0 0.00 7 44618 1393 0 0
srv_aggregate_perf_data 117 117 0 0.00 2733 191758 15951 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 716 128244 35402 0 0
srv_find_qd_qs_mism 1045782 1045782 0 0.00 0 18663 11 0 0
srv_make_invnt_saldo 458 458 0 0.00 88 117614 4722 0 0
srv_make_money_saldo 938 938 0 0.00 1 19555 399 0 0
srv_recalc_idx_stat 4 4 0 0.00 68378 464296 266176 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4858 4858 0 0.00 1 17140 1063 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 1 5786 539 0 0
t$perf-norm:sp_cancel_client_order 1699 1699 0 0.00 0 2274 33 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 746 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 1401 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 526 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 5380 54 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 127746 7821 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 2691 23 0 0
t$perf-norm:sp_client_order 20321 20321 0 0.00 0 14151 42 0 0
t$perf-norm:sp_customer_reserve 937 937 0 0.00 0 4822 21 0 0
t$perf-norm:sp_pay_from_customer 6813 6813 0 0.00 0 5492 21 0 0
t$perf-norm:sp_pay_to_supplier 5593 5593 0 0.00 0 15459 28 0 0
t$perf-norm:sp_reserve_write_off 9659 9659 0 0.00 0 15440 29 0 0
t$perf-norm:sp_supplier_invoice 5510 5510 0 0.00 0 4078 36 0 0
t$perf-norm:sp_supplier_order 5295 5295 0 0.00 0 3003 37 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 694 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 232 8 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 561 16 0 0
t$perf-norm:srv_make_money_saldo 938 938 0 0.00 0 3007 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 166 70 0 0
v_add_invoice_to_stock 9790 9628 162 1.65 0 5670 25 0 162
v_all_customers 37318 37318 0 0.00 0 76 4 0 0
v_all_suppliers 30017 30017 0 0.00 0 123 5 0 0
v_all_wares 40632 40632 0 0.00 0 131 4 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 1545 18 0 0
v_cancel_client_order 3398 3398 0 0.00 0 7824 12 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 1493 13 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 3604 15 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 3471 21 0 0
v_cancel_supplier_invoice 880 868 12 1.36 0 3593 27 0 12
v_cancel_supplier_order 842 842 0 0.00 0 369 11 0 0
v_cancel_write_off 1980 1980 0 0.00 0 851 24 0 0
v_max_id_clo_ord 5289 5289 0 0.00 0 1439 6 0 0
v_max_id_clo_res 937 937 0 0.00 0 4619 17 0 0
v_max_id_ord_sup 5498 5498 0 0.00 0 2155 6 0 0
v_max_non_paid_invoice 1370 1370 0 0.00 0 55 8 0 0
v_max_non_paid_realizn 3500 3500 0 0.00 0 1372 8 0 0
v_min_id_clo_ord 5289 5289 0 0.00 0 4442 12 0 0
v_min_id_clo_res 937 937 0 0.00 0 429 8 0 0
v_min_id_ord_sup 5498 5498 0 0.00 0 5235 8 0 0
v_min_non_paid_invoice 5592 5592 0 0.00 0 4976 14 0 0
v_min_non_paid_realizn 6813 6813 0 0.00 0 5294 18 0 0
v_our_firm 9938 9938 0 0.00 0 68 5 0 0
v_reserve_write_off 19306 19306 0 0.00 0 3837 12 0 0
x_lock_depdocs_on_canc_invoice 11610 11610 0 0.00 0 15269 141 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 9785 412 0 0
x_make_qstorno_1000_1200 5295 5295 0 0.00 3 44597 1210 0 0
x_make_qstorno_1200_2000 5510 5510 0 0.00 3 102632 2958 0 0
x_make_qstorno_2100_3300 454352 454352 0 0.00 0 36655 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-12-2022 13:38:20 to 29-12-2022 13:38:24.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:28 35760 8192000000 9729714272 10026553344 799446448 8.891 100 88 174 1 828416896 7361632 21608816
11:10:11 20914 8192000000 9769351648 10076164096 842205232 9.322 100 89 177 0 875327440 11899520 21222688
11:11:43 15457 8192000000 9816084496 10136203264 875612736 9.656 100 85 170 0 917456032 22074112 19769184
11:13:08 8875 8192000000 9859865024 10197815296 924568672 10.141 100 75 148 0 978646496 38450208 15627616
11:14:20 4095 8192000000 9925971568 10268622848 958159472 10.471 100 91 176 1 1035826624 58865952 18801200
11:15:27 5332 8192000000 9942918400 10297708544 988807408 10.770 100 83 164 0 1068492816 62945408 16740000
11:16:37 1315 8192000000 9991417728 10350350336 1007458416 10.951 100 86 164 3 1107762800 83747280 16557104
11:17:41 1315 8192000000 10008512880 10372587520 1031822448 11.186 100 93 181 0 1127259936 77693728 17743760
11:18:45 1248 8192000000 10019655232 10387247104 1046861920 11.331 100 96 185 1 1140264992 74276208 19126864
11:19:49 1432 8192000000 10011472304 10394107904 1055087008 11.409 100 81 154 0 1148345600 77577296 15681296
11:20:54 803 8192000000 10026717824 10399539200 1063744304 11.492 100 95 180 2 1148026480 65959376 18322800
11:21:58 1282 8192000000 10050387728 10430275584 1069114752 11.544 100 94 182 1 1172510544 84666288 18729504
11:23:02 1227 8192000000 10072344800 10453422080 1076207072 11.611 100 97 195 0 1196226352 100060320 19958960
11:24:05 1185 8192000000 10057702880 10440794112 1087748176 11.721 100 92 185 3 1176195536 69599600 18847760
11:25:09 1149 8192000000 10060055024 10444947456 1090790432 11.750 100 99 183 0 1180531184 71234512 18506240
11:26:13 1120 8192000000 10059241232 10444353536 1093135344 11.772 100 94 175 1 1179177440 67878288 18163808
11:27:15 1238 8192000000 10066695024 10454290432 1099646160 11.834 100 98 184 1 1191021616 73209296 18166160
11:28:18 1071 8192000000 10042543536 10433343488 1089957552 11.742 99 87 170 0 1165324880 58456384 16910944
11:29:20 959 8192000000 10056757168 10451890176 1085276752 11.698 100 76 147 1 1181018240 80629904 15111584
11:30:21 1440 8192000000 10055366720 10444447744 1085427120 11.699 100 95 180 0 1182261040 77470176 19363744
11:31:25 1314 8192000000 10045767264 10431631360 1085671728 11.701 100 93 176 0 1166733568 62807680 18254160
11:32:29 1264 8192000000 10045536768 10429808640 1078180336 11.630 100 96 185 1 1171050288 72779488 20090464
11:33:32 1303 8192000000 10026377168 10404843520 1071891248 11.570 100 89 167 1 1148414896 58580544 17943104
11:34:34 1155 8192000000 9998891584 10374668288 1043291616 11.296 100 93 175 1 1121337648 58408192 19637840
11:35:36 1098 8192000000 10004132288 10378416128 1044437680 11.307 100 94 181 0 1129876912 66550688 18888544
11:36:38 1145 8192000000 10024595552 10398552064 1037841632 11.244 100 89 175 2 1142420480 86821376 17757472
11:37:42 12161 8192000000 10002938784 10389721088 1059382512 11.451 99 60 106 2 1110009200 40135712 10490976
11:39:05 34146 8192000000 10019973408 10403270656 1064770192 11.502 99 62 123 1 1133494272 56639280 12084800
11:40:51 8514 8192000000 10057047696 10448596992 1091085344 11.753 100 76 150 1 1174297136 67479408 15732384
11:42:11 3215 8192000000 10084716448 10469449728 1097888096 11.818 100 78 139 0 1209549680 98723936 12937648
11:43:20 1451 8192000000 10130407920 10515791872 1104147168 11.877 100 92 174 2 1246724864 123058672 19519024
11:44:28 4183 8192000000 10138443008 10535739392 1115749728 11.987 100 79 154 2 1256709968 124415760 16544480
11:45:36 7291 8192000000 10163900160 10567331840 1127545456 12.098 100 77 149 0 1278823392 135272112 16005824
11:46:51 4010 8192000000 10176048720 10576384000 1143566144 12.249 100 66 132 1 1291987472 133735952 14685376
11:47:59 873 8192000000 10242880016 10641940480 1155066176 12.357 100 96 188 0 1355604272 180552400 19985696
11:49:02 3332 8192000000 10251801936 10659540992 1159109600 12.395 100 87 167 1 1362197296 185161632 17926064
11:50:11 1845 8192000000 10290585552 10692599808 1160135264 12.405 100 90 166 0 1406405536 227768336 18501936
11:51:21 1704 8192000000 10317512432 10716692480 1182085280 12.610 100 84 159 1 1428770240 228520272 18164688
11:52:26 1596 8192000000 10330513344 10736922624 1174118592 12.535 99 93 178 3 1441935952 247025664 20791696
11:53:33 1049 8192000000 10374004880 10776879104 1195494480 12.734 100 91 174 0 1480305760 266149984 18661296
11:54:36 912 8192000000 10392449360 10796265472 1197662304 12.755 100 98 189 1 1502241072 282998320 21580448
11:55:41 903 8192000000 10432893872 10847531008 1191036032 12.693 100 96 185 2 1542658160 329201456 22420672
11:56:47 1027 8192000000 10483846944 10891968512 1200705088 12.783 100 93 183 0 1592813024 369841456 22266480
11:57:53 1359 8192000000 10507142256 10933600256 1204658240 12.820 100 91 178 0 1614569280 387582368 22328672
11:58:57 930 8192000000 10537594528 10956910592 1202108192 12.796 100 95 187 1 1644093616 419922576 22062848
12:00:01 991 8192000000 10560864064 10989764608 1205316032 12.826 100 91 174 0 1664446416 437788160 21342224
12:01:07 2159 8192000000 10589941072 10997735424 1192311680 12.705 99 92 183 0 1690805072 475851104 22642288
12:02:14 806 8192000000 10567246112 11030630400 1202925408 12.803 100 92 182 0 1673655376 448439360 22290608
12:03:18 936 8192000000 10558017184 11024109568 1197154080 12.750 100 95 178 3 1662067392 442695152 22218160
12:04:23 695 8192000000 10573117952 11023106048 1191978544 12.702 100 86 173 0 1676250096 463151200 21120352
12:05:28 2661 8192000000 10615301632 11038294016 1200936592 12.785 100 69 136 1 1717515840 499319424 17259824
12:06:39 5352 8192000000 10582639408 11048431616 1191732032 12.699 100 54 104 0 1685173728 479517840 13923856
12:07:57 3271 8192000000 10627675152 11096854528 1194198256 12.722 100 64 128 1 1727765792 516970592 16596944
12:09:06 10741 8192000000 10524972816 10967326720 1193677216 12.718 100 43 87 0 1630170896 425761808 10731872
12:10:28 12951 8192000000 10478850400 10976071680 1174391376 12.538 100 39 76 0 1573887680 389731712 9764592
12:11:44 12938 8192000000 10381108544 10944319488 1155393616 12.360 99 35 70 1 1482958000 318553024 9011360
12:13:01 19589 8192000000 10277640272 10767519744 1147086128 12.282 99 43 85 0 1399917664 242773312 10058224
12:14:28 37668 8192000000 10184992160 10657894400 1151025824 12.319 100 15 30 0 1306879424 152236368 3617232
12:16:21 12308 8192000000 10143638144 10570334208 1156440496 12.370 100 81 146 1 1233425264 61827824 15156944
12:17:39 66653 8192000000 10057014608 10547990528 1083939984 11.685 94 7 12 0 1146069808 60317440 1812384
12:19:46 82412 8192000000 10081924528 10520453120 1103233696 11.868 97 45 90 1 1150951072 37606320 10111056
12:22:40 83291 8192000000 10057011280 10524717056 1138203776 12.199 100 40 80 0 1200033792 52807056 9022960
12:25:04 83124 8192000000 10073879152 10538872832 1137288720 12.190 100 25 45 1 1216795936 74855680 4651536
12:27:29 41213 8192000000 10102763888 10546126848 1137683008 12.194 99 18 34 0 1181518496 40090496 3744992
12:29:22 4673 8192000000 10176484640 10609807360 1153063648 12.338 100 56 108 0 1269849040 104855024 11930368
12:30:38 8611 8192000000 10178505888 10603331584 1140467664 12.220 99 55 110 1 1275709024 122964528 12276832
12:31:48 23879 8192000000 10212094864 10686238720 1163321472 12.434 100 44 86 0 1298781216 125558752 9900992
12:33:42 59515 8192000000 10224465696 10690183168 1160875456 12.411 99 20 38 1 1323089712 157243264 4970992
12:36:32 83617 8192000000 10175214896 10715570176 1154807072 12.355 100 22 44 0 1324236976 163753088 5676816
12:39:19 128241 8192000000 10067496448 10524160000 1081627520 11.663 94 12 24 0 1120494208 36889968 1976720
12:42:28 29624 8192000000 10101733168 10543161344 1122746096 12.053 99 43 77 0 1192865568 62224704 7894768
12:44:10 53471 8192000000 10093742336 10546749440 1124937728 12.074 98 25 48 0 1188744192 59085856 4720608
12:46:05 74356 8192000000 10121491152 10564874240 1106498960 11.899 96 16 30 0 1199266432 89401664 3365808
12:48:20 118073 8192000000 10070645792 10528288768 1073663200 11.587 94 9 18 0 1105971360 30272896 2035264
12:51:19 91280 8192000000 10090937152 10525511680 1118151664 12.010 99 48 96 1 1182757104 54798560 9806880
12:53:52 63552 8192000000 10105526832 10560008192 1102448992 11.861 98 40 78 0 1180106864 69971200 7686672
12:56:05 21693 8192000000 10055088080 10498019328 1074934592 11.599 96 40 75 1 1131053504 47917584 8201328
12:57:38 46627 8192000000 10064075456 10508275712 1123465232 12.060 100 34 69 1 1159649008 29023024 7160752
12:59:34 70960 8192000000 10125212448 10581901312 1140908112 12.224 100 18 36 0 1221323792 76351104 4064576
13:01:45 90443 8192000000 10126715312 10620841984 1134036048 12.159 99 14 28 0 1240162560 102836816 3289696
13:04:18 66935 8192000000 10093978032 10560733184 1104802192 11.883 98 8 16 0 1158361872 51825248 1734432
13:06:26 25248 8192000000 10078643600 10517569536 1086736192 11.712 97 23 44 0 1158981472 67391056 4854224
13:07:56 62047 8192000000 10077962864 10556424192 1133445648 12.154 100 17 34 0 1203969264 67005584 3518032
13:10:00 83103 8192000000 10104015424 10548944896 1089272208 11.736 96 36 68 2 1170700640 74309072 7119360
13:12:24 19935 8192000000 10100190480 10543177728 1134481296 12.164 100 45 90 0 1191407328 47788160 9137872
13:14:12 30182 8192000000 10124470880 10600783872 1125149344 12.076 99 39 76 1 1222835136 89975040 7710752
13:15:44 26308 8192000000 10150425968 10588745728 1130593120 12.127 99 42 79 1 1229702336 90477744 8631472
13:17:14 87640 8192000000 10074296672 10546102272 1105123520 11.886 96 12 24 0 1170768256 62946800 2697936
13:19:43 85560 8192000000 10082091632 10522619904 1105428960 11.889 97 27 54 1 1158390288 47713552 5247776
13:23:05 89424 8192000000 10091360672 10621075456 1130413216 12.125 99 21 37 1 1213482848 78709840 4359792
13:25:41 54051 8192000000 10114513648 10566930432 1147211680 12.283 100 17 32 1 1212095952 61129232 3755040
13:27:38 87758 8192000000 10092574176 10575261696 1134248832 12.161 99 9 18 0 1177450688 41198688 2003168
13:30:06 43932 8192000000 10110951984 10592227328 1119264528 12.020 97 22 42 1 1186294544 62622160 4407856
13:31:50 32684 8192000000 10115782816 10555920384 1124228736 12.067 98 34 69 1 1182637424 51354640 7054048
13:33:29 61480 8192000000 10131471568 10606739456 1138036880 12.197 99 28 54 0 1241032784 97294928 5700976
13:35:37 82705 8192000000 10110210800 10575261696 1126920576 12.092 98 23 46 0 1178085360 46567536 4597248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-12-2022 13:38:26 to 29-12-2022 13:38:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-12-2022 13:38:43 to 29-12-2022 13:38:45.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          211789
MON$OLDEST_ACTIVE               213781
MON$OLDEST_SNAPSHOT             213781
MON$NEXT_TRANSACTION            213781
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2083808
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             759
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:680: 
:681: mscs-test-161.budzdorov.ru    Thu Dec 29 13:37:24 2022
:682:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:683: 
:684: 
:685: mscs-test-161.budzdorov.ru    Thu Dec 29 13:37:26 2022
:686:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:687: 
:688: 
:689: mscs-test-161.budzdorov.ru    Thu Dec 29 13:37:31 2022
:690:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:691: 
--- end of diff output ---

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

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