Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.02.2023 13:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14826568 kB
MemAvailable 30383812 kB
Buffers 0 kB
Cached 16848460 kB
SwapCached 3384 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-14 13:37:27
DTS_END                         2023-02-14 13:37:27.5020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52492
TRN_ID                          227942
ATT_ID                          600
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: 9147.25 20691 1097670
customer order: creation 180.29 471 21635
customer order: refuse 15.55 401 1866
order to supplier: creation 47.83 1319 5740
order to supplier: removal 3.38 162280 406
invoice (draft): creation 48.83 3062 5860
invoice (draft): removal 50.33 1047 6040
invoice accept: apply 43.56 25602 5227
invoice accept: cancel 44.55 1212 5346
customer reserve: creation 4009.57 244 481148
customer reserve: removal 4408.75 98 529050
realization accept: apply 86.96 276 10435
realization accept: cancel 73.32 173 8798
payment from customer: creation 60.19 165 7223
payment from customer: removal 5.78 112 694
payment to supplier: creation 52.13 194 6255
payment to supplier: removal 3.38 112 405
service: total inventory turnovers 4.08 3749 489
service: total monetary turnovers 7.79 281 935
service: aggregate perf. data 0.94 14603 113
service: refresh index statistics 0.04 198411 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-02-2023 13:38:45 to 14-02-2023 13:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9029
TEST_TIME 2 6145
TEST_TIME 3 6030
TEST_TIME 4 6054
TEST_TIME 5 6706
TEST_TIME 6 6797
TEST_TIME 7 7504
TEST_TIME 8 7311
TEST_TIME 9 7229
TEST_TIME 10 7344
TEST_TIME 11 7348
TEST_TIME 12 7226
TEST_TIME 13 7577
TEST_TIME 14 7656
TEST_TIME 15 7690
TEST_TIME 16 7925
TEST_TIME 17 8060
TEST_TIME 18 8247
TEST_TIME 19 8193
TEST_TIME 20 8296
TEST_TIME 21 8350
TEST_TIME 22 8712
TEST_TIME 23 8702
TEST_TIME 24 9034
TEST_TIME 25 9198
TEST_TIME 26 9143
TEST_TIME 27 9627
TEST_TIME 28 10275
TEST_TIME 29 10566
TEST_TIME 30 10669
TEST_TIME 31 10804
TEST_TIME 32 11175
TEST_TIME 33 11228
TEST_TIME 34 11220
TEST_TIME 35 11093
TEST_TIME 36 10940
TEST_TIME 37 11115
TEST_TIME 38 10933
TEST_TIME 39 10869
TEST_TIME 40 10769
TEST_TIME 41 10646
TEST_TIME 42 10495
TEST_TIME 43 10426
TEST_TIME 44 10355
TEST_TIME 45 10235
TEST_TIME 46 10272
TEST_TIME 47 10190
TEST_TIME 48 10079
TEST_TIME 49 10069
TEST_TIME 50 10098
TEST_TIME 51 10004
TEST_TIME 52 9908
TEST_TIME 53 9933
TEST_TIME 54 9787
TEST_TIME 55 9849
TEST_TIME 56 10082
TEST_TIME 57 10014
TEST_TIME 58 10009
TEST_TIME 59 9942
TEST_TIME 60 9898
TEST_TIME 61 9893
TEST_TIME 62 9868
TEST_TIME 63 9880
TEST_TIME 64 9837
TEST_TIME 65 10045
TEST_TIME 66 10115
TEST_TIME 67 10111
TEST_TIME 68 10204
TEST_TIME 69 10195
TEST_TIME 70 10079
TEST_TIME 71 10018
TEST_TIME 72 9954
TEST_TIME 73 9903
TEST_TIME 74 9820
TEST_TIME 75 9753
TEST_TIME 76 9674
TEST_TIME 77 9648
TEST_TIME 78 9573
TEST_TIME 79 9555
TEST_TIME 80 9504
TEST_TIME 81 9440
TEST_TIME 82 9404
TEST_TIME 83 9356
TEST_TIME 84 9319
TEST_TIME 85 9296
TEST_TIME 86 9308
TEST_TIME 87 9315
TEST_TIME 88 9337
TEST_TIME 89 9292
TEST_TIME 90 9297
TEST_TIME 91 9309
TEST_TIME 92 9317
TEST_TIME 93 9284
TEST_TIME 94 9293
TEST_TIME 95 9314
TEST_TIME 96 9341
TEST_TIME 97 9375
TEST_TIME 98 9384
TEST_TIME 99 9362
TEST_TIME 100 9322
TEST_TIME 101 9267
TEST_TIME 102 9295
TEST_TIME 103 9236
TEST_TIME 104 9188
TEST_TIME 105 9134
TEST_TIME 106 9076
TEST_TIME 107 9105
TEST_TIME 108 9060
TEST_TIME 109 9051
TEST_TIME 110 9096
TEST_TIME 111 9065
TEST_TIME 112 9086
TEST_TIME 113 9081
TEST_TIME 114 9080
TEST_TIME 115 9098
TEST_TIME 116 9090
TEST_TIME 117 9176
TEST_TIME 118 9153
TEST_TIME 119 9225
TEST_TIME 120 9186

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-02-2023 13:38:49 to 14-02-2023 13:38:52.

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 1540457 1540457 0 0.00 0 18380 14 0 0
doc_list_biud 1540439 1540439 0 0.00 0 20698 40 0 0
sp_add_invoice_to_stock 5306 5227 79 1.48 43 186737 25602 0 79
sp_cancel_adding_invoice 5346 5346 0 0.00 4 94866 1212 0 0
sp_cancel_client_order 1866 1866 0 0.00 6 9715 402 0 0
sp_cancel_customer_reserve 529050 529050 0 0.00 1 20701 99 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 4 3102 113 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 4 4241 113 0 0
sp_cancel_supplier_invoice 6047 6040 7 0.11 3 18685 1048 0 7
sp_cancel_supplier_order 406 406 0 0.00 12 1511760 162281 0 0
sp_cancel_write_off 8798 8798 0 0.00 2 12988 174 0 0
sp_client_order 21635 21635 0 0.00 5 19742 471 0 0
sp_customer_reserve 481148 481148 0 0.00 0 91991 244 0 0
sp_fill_shopping_cart 33226 33226 0 0.00 1 16492 86 0 0
sp_fill_shopping_cart_clo_res 481112 481112 0 0.00 0 16571 18 0 0
sp_get_clo_for_invoice 5186 5186 0 0.00 0 37991 340 0 0
sp_kill_cost_storno 15243 15243 0 0.00 0 18374 67 0 0
sp_kill_qstorno_ret_qs2qd 1073344 1073344 0 0.00 0 20618 44 0 0
sp_kill_qty_storno 567122 567122 0 0.00 0 20697 100 0 0
sp_lock_selected_doc 21506 21506 0 0.00 0 7109 9 0 0
sp_make_cost_storno 29098 29098 0 0.00 0 15567 79 0 0
sp_multiply_rows_for_pdistr 29096 29096 0 0.00 0 15654 37 0 0
sp_multiply_rows_for_qdistr 21635 21635 0 0.00 0 19717 173 0 0
sp_pay_from_customer 7223 7223 0 0.00 5 7701 166 0 0
sp_pay_to_supplier 6255 6255 0 0.00 6 15757 194 0 0
sp_payment_common 13477 13477 0 0.00 2 7543 46 0 0
sp_qd_handle_on_cancel_clo 1866 1866 0 0.00 0 9686 310 0 0
sp_qd_handle_on_invoice_upd_sts 10530 10530 0 0.00 0 11422 501 0 0
sp_qd_handle_on_reserve_upd_sts 10432 10432 0 0.00 0 9221 59 0 0
sp_reserve_write_off 10435 10435 0 0.00 8 13388 276 0 0
sp_supplier_invoice 5860 5860 0 0.00 8 91695 3062 0 0
sp_supplier_order 5740 5740 0 0.00 7 23063 1319 0 0
srv_aggregate_perf_data 113 113 0 0.00 2259 129120 14604 0 0
srv_fill_mon_cache_memory 76 76 0 0.00 769 125772 29422 0 0
srv_find_qd_qs_mism 1046512 1046512 0 0.00 0 18303 11 0 0
srv_make_invnt_saldo 489 489 0 0.00 38 62907 3749 0 0
srv_make_money_saldo 935 935 0 0.00 1 9424 282 0 0
srv_recalc_idx_stat 5 5 0 0.00 56763 336707 198412 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 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 5235 5235 0 0.00 1 16038 944 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 1 21070 467 0 0
t$perf-norm:sp_cancel_client_order 1866 1866 0 0.00 0 1209 27 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 1255 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 12026 32 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 1057 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 1039 28 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 87303 6843 0 0
t$perf-norm:sp_cancel_write_off 1002 1002 0 0.00 0 2340 19 0 0
t$perf-norm:sp_client_order 21635 21635 0 0.00 0 9140 36 0 0
t$perf-norm:sp_customer_reserve 1072 1072 0 0.00 0 10092 26 0 0
t$perf-norm:sp_pay_from_customer 7223 7223 0 0.00 0 11117 22 0 0
t$perf-norm:sp_pay_to_supplier 6255 6255 0 0.00 0 9039 20 0 0
t$perf-norm:sp_reserve_write_off 10436 10436 0 0.00 0 5502 21 0 0
t$perf-norm:sp_supplier_invoice 5860 5860 0 0.00 0 10366 32 0 0
t$perf-norm:sp_supplier_order 5740 5740 0 0.00 0 6132 32 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 218 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 76 76 0 0.00 0 252 11 0 0
t$perf-norm:srv_make_invnt_saldo 489 489 0 0.00 0 1543 15 0 0
t$perf-norm:srv_make_money_saldo 935 935 0 0.00 0 253 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 163 61 0 0
v_add_invoice_to_stock 10510 10352 158 1.50 0 7647 29 0 158
v_all_customers 38876 38876 0 0.00 0 180 4 0 0
v_all_suppliers 32578 32578 0 0.00 0 186 4 0 0
v_all_wares 43268 43268 0 0.00 0 169 4 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 2421 19 0 0
v_cancel_client_order 3732 3732 0 0.00 0 4397 12 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 3023 15 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 4528 14 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 1747 15 0 0
v_cancel_supplier_invoice 882 868 14 1.58 0 2798 29 0 14
v_cancel_supplier_order 812 812 0 0.00 0 2019 13 0 0
v_cancel_write_off 2004 2004 0 0.00 0 4659 31 0 0
v_max_id_clo_ord 5737 5737 0 0.00 0 598 5 0 0
v_max_id_clo_res 1072 1072 0 0.00 0 1510 15 0 0
v_max_id_ord_sup 5853 5853 0 0.00 0 3403 8 0 0
v_max_non_paid_invoice 1556 1556 0 0.00 0 431 8 0 0
v_max_non_paid_realizn 3971 3971 0 0.00 0 2970 8 0 0
v_min_id_clo_ord 5737 5737 0 0.00 0 2290 8 0 0
v_min_id_clo_res 1072 1072 0 0.00 0 6625 21 0 0
v_min_id_ord_sup 5853 5853 0 0.00 0 4406 8 0 0
v_min_non_paid_invoice 6255 6255 0 0.00 0 5633 18 0 0
v_min_non_paid_realizn 7222 7222 0 0.00 0 4563 18 0 0
v_our_firm 10892 10892 0 0.00 0 187 5 0 0
v_reserve_write_off 20864 20864 0 0.00 0 4600 13 0 0
x_lock_depdocs_on_canc_invoice 10952 10952 0 0.00 0 23709 127 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 17743 408 0 0
x_make_qstorno_1000_1200 5740 5740 0 0.00 3 18155 1142 0 0
x_make_qstorno_1200_2000 5860 5860 0 0.00 4 91650 2836 0 0
x_make_qstorno_2100_3300 481147 481147 0 0.00 0 91983 214 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-02-2023 13:38:53 to 14-02-2023 13:38:57.

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:31 6 8192000000 8803696912 8816943104 8610352 0.104 3 3 4 0 8805520 108208 86960
11:08:32 22549 8192000000 9711570256 10008350720 788635408 8.781 100 85 170 0 817666544 7561312 21469824
11:09:59 23452 8192000000 9757959024 10065436672 831289664 9.212 100 85 169 0 862639776 11214960 20135152
11:11:28 12126 8192000000 9795206944 10115297280 866557344 9.566 100 85 170 0 905172368 18989872 19625152
11:12:48 9253 8192000000 9866794256 10200948736 920954512 10.105 100 79 158 1 969425072 31296800 17173760
11:14:00 3647 8192000000 9908131264 10253037568 959458016 10.484 100 88 176 0 1025688448 48070992 18159440
11:15:07 4171 8192000000 9963068224 10319241216 991951008 10.800 100 81 158 1 1076421440 68935216 15535216
11:16:14 2445 8192000000 9998127488 10356744192 1011876000 10.994 100 86 170 1 1119551488 90837328 16838160
11:17:18 942 8192000000 10012642640 10380414976 1039279968 11.258 100 96 192 0 1132531312 74295344 18956000
11:18:20 694 8192000000 10031644880 10405081088 1052885968 11.388 100 95 186 1 1155853184 83700624 19266592
11:19:22 1795 8192000000 10054676576 10429034496 1057297296 11.431 100 95 183 2 1176496432 101112496 18086640
11:20:26 1185 8192000000 10019363488 10394411008 1064767616 11.502 100 99 186 3 1139060448 57250512 17042320
11:21:29 1142 8192000000 10051340496 10431397888 1071972288 11.571 100 99 196 1 1170132224 78597248 19562688
11:22:31 1273 8192000000 10034180352 10425188352 1077503312 11.624 100 86 170 1 1159771920 65303232 16965376
11:23:33 1160 8192000000 10062679328 10451288064 1083214688 11.678 100 92 179 0 1182518576 80736112 18567776
11:24:37 1079 8192000000 10042594928 10424487936 1083476832 11.681 100 98 179 0 1159172848 58791872 16904144
11:25:38 5282 8192000000 10066376352 10457382912 1088951248 11.733 100 71 139 0 1187265952 84729920 13584784
11:26:44 973 8192000000 10052618560 10439790592 1086451744 11.709 100 89 169 1 1173811632 69170880 18189008
11:27:46 1977 8192000000 10030397680 10425745408 1084622816 11.692 100 62 110 2 1152182464 55861664 11697984
11:28:50 991 8192000000 10045353248 10437505024 1086945216 11.714 100 90 171 2 1164673888 60774272 16954400
11:29:52 2673 8192000000 10045896912 10435821568 1089756464 11.740 100 75 142 1 1166890928 62956336 14178128
11:30:56 1184 8192000000 10012064768 10392850432 1060141184 11.458 100 93 173 1 1137056352 58417904 18497264
11:31:58 1363 8192000000 10007528832 10385682432 1049336064 11.354 100 98 183 6 1131351216 62473984 19541168
11:33:00 1284 8192000000 9995560544 10365407232 1047151648 11.333 99 94 181 5 1118381088 52932160 18297280
11:34:02 2425 8192000000 9987717296 10368290816 1058661360 11.444 100 78 138 2 1120432016 48415632 13355024
11:35:05 6560 8192000000 9935191904 10304380928 999970160 10.878 97 67 118 2 1049701104 37960032 11770912
11:36:12 1547 8192000000 9993868416 10369355776 1039990144 11.265 100 85 155 3 1115130432 58428736 16711552
11:37:15 1073 8192000000 9992329696 10366857216 1035761808 11.224 99 76 148 0 1114782160 64531408 14488944
11:38:25 1858 8192000000 10007191984 10390335488 1067064192 11.524 100 91 171 1 1135037296 47467520 20505584
11:39:35 2452 8192000000 10027751936 10411053056 1077648576 11.625 100 73 132 0 1147246240 55981424 13616240
11:40:40 1591 8192000000 10081100912 10464321536 1088380672 11.727 100 90 168 0 1198321360 92437344 17503344
11:41:48 1133 8192000000 10094722224 10482483200 1103904800 11.875 100 91 166 1 1212589120 91388320 17296000
11:42:59 4910 8192000000 10125332768 10517606400 1118128176 12.009 100 74 146 0 1245218128 111793136 15296816
11:44:10 1326 8192000000 10130114704 10546847744 1121446304 12.041 100 95 178 4 1246652304 105351792 19854208
11:45:20 6119 8192000000 10153334352 10553061376 1138685808 12.203 100 68 129 0 1265589136 113153856 13749472
11:46:30 3825 8192000000 10205974496 10608934912 1146294592 12.275 100 78 153 1 1318694112 155643728 16755792
11:47:38 996 8192000000 10221889632 10618576896 1150749328 12.317 100 84 166 3 1334488672 166787344 16952000
11:48:42 1169 8192000000 10248269712 10648756224 1157551712 12.380 100 89 171 2 1358823280 182528000 18743568
11:49:46 1216 8192000000 10295760640 10703998976 1168277184 12.481 100 89 173 1 1402894912 214340400 20277328
11:50:51 918 8192000000 10324528080 10742857728 1177550464 12.567 100 94 181 3 1434183808 235596752 21036592
11:51:55 920 8192000000 10351758976 10757918720 1184033680 12.628 100 97 191 0 1463980640 258235040 21711920
11:52:59 2153 8192000000 10361223072 10782351360 1185037456 12.637 100 91 176 0 1462323664 257460480 19825728
11:54:04 789 8192000000 10393431568 10806403072 1194657264 12.727 100 93 179 0 1503078400 287357632 21063504
11:55:08 1139 8192000000 10393846880 10821238784 1184070544 12.628 100 94 188 0 1506362336 299949264 22342528
11:56:16 1039 8192000000 10430654256 10841088000 1199757680 12.774 100 80 152 2 1532584048 314358384 18467984
11:57:20 833 8192000000 10471921568 10900779008 1201872384 12.794 100 97 193 1 1579629712 354952400 22804928
11:58:24 760 8192000000 10475494576 10908770304 1195879040 12.738 100 96 187 2 1578022912 359689664 22454208
11:59:27 788 8192000000 10472145856 10905980928 1182136640 12.610 100 88 173 0 1577325280 374548784 20639856
12:00:34 3398 8192000000 10449734976 10892836864 1179270720 12.583 100 58 110 0 1560114480 366686272 14157488
12:01:42 4984 8192000000 10479750368 10912964608 1171776208 12.513 100 58 114 1 1584895504 398295888 14823408
12:02:57 25734 8192000000 10427914976 10885513216 1148548688 12.296 100 40 80 0 1547753168 388164016 11040464
12:04:25 2405 8192000000 10322934656 10836701184 1146704208 12.279 100 68 133 2 1424523840 260741920 17077712
12:05:29 16681 8192000000 10289564768 10743619584 1148435888 12.295 99 57 114 0 1386863856 225442832 12985136
12:06:50 56560 8192000000 10217786832 10681307136 1089726192 11.740 94 26 47 0 1281609392 185237536 6645664
12:08:47 53387 8192000000 10134903696 10613612544 1125996064 12.084 98 14 28 0 1293589104 163954192 3638848
12:10:41 44830 8192000000 10070883840 10531319808 1134681136 12.165 99 32 64 0 1176139056 34297120 7160800
12:12:28 3709 8192000000 10067267632 10486722560 1124154464 12.066 100 77 140 3 1176321856 37841360 14326032
12:13:39 37185 8192000000 10113369248 10569756672 1118137232 12.009 99 24 46 1 1207945360 84807152 5000976
12:15:18 26647 8192000000 10068403440 10486161408 1124843488 12.073 99 60 109 2 1154294992 18006848 11444656
12:16:50 29632 8192000000 10073395936 10510680064 1120128608 12.028 99 54 96 1 1180430112 49697680 10603824
12:18:23 84051 8192000000 10051524928 10492121088 1113082928 11.962 99 38 73 2 1177919488 56401600 8434960
12:20:53 22125 8192000000 10071651728 10521276416 1114773856 11.978 99 57 103 3 1180181744 53549616 11858272
12:22:23 60285 8192000000 10105810256 10529665024 1121251728 12.039 99 54 101 2 1191733120 58571776 11909616
12:24:24 23395 8192000000 10114737744 10546024448 1144866432 12.261 100 44 82 0 1215013344 60111936 10034976
12:25:48 12217 8192000000 10148060896 10577051648 1145934528 12.271 99 52 104 0 1235468800 77675568 11858704
12:27:02 29430 8192000000 10177271088 10632835072 1147275568 12.284 99 23 46 0 1268105280 115137120 5692592
12:28:31 24304 8192000000 10165130704 10617556992 1154257472 12.349 100 16 30 0 1261836864 104064880 3514512
12:29:59 89102 8192000000 10209903024 10690113536 1163216544 12.433 100 23 45 0 1324549168 155715152 5617472
12:32:29 61954 8192000000 10119522000 10584449024 1102753776 11.864 96 13 26 0 1234215568 128229008 3232784
12:34:38 14664 8192000000 10125823248 10552946688 1145986112 12.272 100 68 130 1 1223893136 61772176 16134848
12:35:54 22971 8192000000 10193493872 10634952704 1162906432 12.430 100 33 64 1 1263940512 94005872 7028208
12:37:18 9785 8192000000 10242085472 10671632384 1165870240 12.458 99 42 84 0 1328542336 153552960 9119136
12:38:31 28815 8192000000 10267531056 10709004288 1156679248 12.372 99 28 55 0 1349863424 186836784 6347392
12:40:03 30281 8192000000 10258513696 10706235392 1136212272 12.180 98 25 48 0 1343662064 201114384 6335408
12:41:33 20872 8192000000 10306744416 10759663616 1140755136 12.223 98 45 85 0 1390960560 240100384 10105040
12:42:56 32022 8192000000 10170623792 10670456832 1133021856 12.150 99 24 48 0 1305678464 167090656 5565952
12:44:38 9823 8192000000 10181018480 10616901632 1137324224 12.190 100 66 130 1 1272075600 120811120 13940256
12:45:49 101515 8192000000 10057561776 10537332736 1124312416 12.068 99 28 56 0 1172229440 41937504 5979520
12:48:32 63010 8192000000 10046014800 10509492224 1098053696 11.819 97 14 28 0 1156742400 55431312 3257392
12:50:56 57387 8192000000 10048628480 10518462464 1116904624 11.998 99 10 19 0 1146226464 27841456 1480384
12:53:05 66661 8192000000 10049649040 10498170880 1029507008 11.164 91 17 34 1 1089524736 56535184 3482544
12:55:17 44018 8192000000 10050987888 10471608320 1113543824 11.966 100 60 120 1 1147045200 20188592 13312784
12:57:13 77258 8192000000 10067679760 10502246400 1100876288 11.846 98 27 53 0 1137478080 31251296 5350496
12:59:35 19286 8192000000 10100018608 10541297664 1119941744 12.026 100 50 99 0 1183497264 53080912 10474608
13:01:01 53608 8192000000 10090832032 10550296576 1111257296 11.944 98 19 38 1 1185612512 70153840 4201376
13:04:23 52658 8192000000 10061282208 10531115008 1112095952 11.952 99 16 32 0 1183483952 68024016 3363984
13:06:21 11954 8192000000 10078451760 10509078528 1110841136 11.940 99 48 94 0 1158071040 38232240 8997664
13:07:36 14571 8192000000 10109816928 10536484864 1120716720 12.034 99 64 124 0 1191293312 57528144 13048448
13:08:51 31509 8192000000 10078221200 10536558592 1123396752 12.059 99 32 64 0 1197786096 67697456 6691888
13:10:24 3690 8192000000 10098001072 10516393984 1118812576 12.016 99 67 132 0 1182031616 50301712 12917328
13:11:29 8413 8192000000 10100215984 10521530368 1107988592 11.913 99 59 116 1 1191526736 72020384 11517760
13:12:41 24030 8192000000 10107178944 10531147776 1134575360 12.164 100 51 102 0 1189546752 45165536 9805856
13:14:11 76115 8192000000 10118777024 10611527680 1121410688 12.040 99 15 30 1 1170356240 46044928 2900624
13:16:35 125771 8192000000 10116727216 10566717440 1139844608 12.214 100 29 58 0 1223667136 77185376 6637152
13:19:41 44559 8192000000 10068205968 10584514560 1137230528 12.189 100 8 17 1 1190309440 51481056 1597856
13:21:26 80278 8192000000 10101789328 10569388032 1148294864 12.293 100 16 32 1 1202820288 51068512 3456912
13:23:47 111602 8192000000 10116665120 10565033984 1142504400 12.239 99 15 30 0 1197221504 51753392 2963712
13:26:47 42459 8192000000 10076414080 10571821056 1084035136 11.686 95 22 44 0 1164371584 75393952 4942496
13:28:36 18912 8192000000 10115053904 10556452864 1116877632 11.997 98 35 71 0 1195543904 70008000 8658272
13:30:00 47922 8192000000 10117899344 10579939328 1116183440 11.991 99 15 30 0 1175664464 56404256 3076768
13:31:49 40621 8192000000 10159701152 10598993920 1128815264 12.110 99 41 82 0 1238952432 101308112 8829056
13:33:36 49330 8192000000 10138485600 10630881280 1116625520 11.995 98 25 50 0 1228576096 106639440 5311136
13:35:42 11549 8192000000 10117129200 10534035456 1131729632 12.138 100 72 134 2 1198386736 53951920 12705184
13:36:56 39944 8192000000 9117258528 9414860800 244025520 2.892 22 3 6 0 256374160 11861232 487408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 14-02-2023 13:38:59 to 14-02-2023 13:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 79 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 14-02-2023 13:39:17 to 14-02-2023 13:39:19.

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          228235
MON$OLDEST_ACTIVE               228236
MON$OLDEST_SNAPSHOT             228236
MON$NEXT_TRANSACTION            228236
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                       2086176
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2900 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2900 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2900 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2900 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 ---
:36: 
:37: mscs-test-161.budzdorov.ru    Tue Feb 14 13:37:42 2023
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
--- end of diff output ---

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

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