Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.12.2022 07: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 14520428 kB
MemAvailable 30051392 kB
Buffers 0 kB
Cached 17137020 kB
SwapCached 3224 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-23 07:37:22
DTS_END                         2022-12-23 07:37:22.1980
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/54842
TRN_ID                          218766
ATT_ID                          626
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: 9215.11 24896 1105813
customer order: creation 168.88 477 20265
customer order: refuse 14.73 443 1767
order to supplier: creation 44.56 1498 5347
order to supplier: removal 3.53 183378 424
invoice (draft): creation 45.95 3382 5514
invoice (draft): removal 52.10 1102 6252
invoice accept: apply 41.58 28524 4989
invoice accept: cancel 47.10 1216 5652
customer reserve: creation 3917.35 267 470082
customer reserve: removal 4581.08 110 549730
realization accept: apply 82.33 284 9879
realization accept: cancel 87.83 188 10539
payment from customer: creation 57.68 179 6922
payment from customer: removal 5.89 135 707
payment to supplier: creation 48.48 195 5818
payment to supplier: removal 3.31 132 397
service: total inventory turnovers 3.82 4804 458
service: total monetary turnovers 7.94 418 953
service: aggregate perf. data 0.94 15954 113
service: refresh index statistics 0.04 255237 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-12-2022 07:38:39 to 23-12-2022 07:38:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6591
TEST_TIME 2 5168
TEST_TIME 3 5299
TEST_TIME 4 5241
TEST_TIME 5 5170
TEST_TIME 6 5505
TEST_TIME 7 5835
TEST_TIME 8 5994
TEST_TIME 9 6194
TEST_TIME 10 6167
TEST_TIME 11 6199
TEST_TIME 12 6208
TEST_TIME 13 6251
TEST_TIME 14 6375
TEST_TIME 15 6579
TEST_TIME 16 6852
TEST_TIME 17 6917
TEST_TIME 18 6901
TEST_TIME 19 7065
TEST_TIME 20 7210
TEST_TIME 21 7387
TEST_TIME 22 7783
TEST_TIME 23 7988
TEST_TIME 24 7940
TEST_TIME 25 8090
TEST_TIME 26 8099
TEST_TIME 27 8063
TEST_TIME 28 8356
TEST_TIME 29 8478
TEST_TIME 30 8627
TEST_TIME 31 9051
TEST_TIME 32 9458
TEST_TIME 33 9475
TEST_TIME 34 10111
TEST_TIME 35 10027
TEST_TIME 36 10430
TEST_TIME 37 10657
TEST_TIME 38 10836
TEST_TIME 39 10971
TEST_TIME 40 10769
TEST_TIME 41 10925
TEST_TIME 42 10764
TEST_TIME 43 10590
TEST_TIME 44 10519
TEST_TIME 45 10344
TEST_TIME 46 10269
TEST_TIME 47 10290
TEST_TIME 48 10108
TEST_TIME 49 9990
TEST_TIME 50 9872
TEST_TIME 51 9915
TEST_TIME 52 9857
TEST_TIME 53 9785
TEST_TIME 54 9783
TEST_TIME 55 9790
TEST_TIME 56 9702
TEST_TIME 57 9630
TEST_TIME 58 9561
TEST_TIME 59 9479
TEST_TIME 60 9890
TEST_TIME 61 9874
TEST_TIME 62 9873
TEST_TIME 63 9856
TEST_TIME 64 9854
TEST_TIME 65 9873
TEST_TIME 66 9790
TEST_TIME 67 9738
TEST_TIME 68 9878
TEST_TIME 69 9995
TEST_TIME 70 9927
TEST_TIME 71 9916
TEST_TIME 72 9938
TEST_TIME 73 9939
TEST_TIME 74 9815
TEST_TIME 75 9781
TEST_TIME 76 9732
TEST_TIME 77 9642
TEST_TIME 78 9581
TEST_TIME 79 9567
TEST_TIME 80 9540
TEST_TIME 81 9531
TEST_TIME 82 9526
TEST_TIME 83 9505
TEST_TIME 84 9443
TEST_TIME 85 9421
TEST_TIME 86 9416
TEST_TIME 87 9386
TEST_TIME 88 9373
TEST_TIME 89 9410
TEST_TIME 90 9418
TEST_TIME 91 9397
TEST_TIME 92 9386
TEST_TIME 93 9467
TEST_TIME 94 9446
TEST_TIME 95 9490
TEST_TIME 96 9511
TEST_TIME 97 9504
TEST_TIME 98 9516
TEST_TIME 99 9458
TEST_TIME 100 9433
TEST_TIME 101 9371
TEST_TIME 102 9315
TEST_TIME 103 9340
TEST_TIME 104 9326
TEST_TIME 105 9296
TEST_TIME 106 9307
TEST_TIME 107 9266
TEST_TIME 108 9273
TEST_TIME 109 9234
TEST_TIME 110 9266
TEST_TIME 111 9213
TEST_TIME 112 9219
TEST_TIME 113 9244
TEST_TIME 114 9222
TEST_TIME 115 9201
TEST_TIME 116 9164
TEST_TIME 117 9145
TEST_TIME 118 9164
TEST_TIME 119 9247
TEST_TIME 120 9243

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-12-2022 07:38:43 to 23-12-2022 07:38:46.

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 1537138 1537138 0 0.00 0 12831 14 0 0
doc_list_biud 1537123 1537123 0 0.00 0 53415 46 0 0
sp_add_invoice_to_stock 5080 4989 91 1.79 30 203659 28525 0 91
sp_cancel_adding_invoice 5652 5652 0 0.00 6 97527 1216 0 0
sp_cancel_client_order 1767 1767 0 0.00 5 9610 443 0 0
sp_cancel_customer_reserve 549730 549730 0 0.00 1 53447 111 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 3622 135 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 4 1582 133 0 0
sp_cancel_supplier_invoice 6259 6252 7 0.11 3 13243 1103 0 7
sp_cancel_supplier_order 424 424 0 0.00 8 1731785 183379 0 0
sp_cancel_write_off 10539 10539 0 0.00 2 23436 189 0 0
sp_client_order 20265 20265 0 0.00 5 14132 478 0 0
sp_customer_reserve 470082 470082 0 0.00 1 50710 267 0 0
sp_fill_shopping_cart 31120 31120 0 0.00 1 24324 86 0 0
sp_fill_shopping_cart_clo_res 470046 470046 0 0.00 0 13184 20 0 0
sp_get_clo_for_invoice 4936 4936 0 0.00 0 28295 290 0 0
sp_kill_cost_storno 17295 17295 0 0.00 0 9888 77 0 0
sp_kill_qstorno_ret_qs2qd 1116675 1116675 0 0.00 0 53399 48 0 0
sp_kill_qty_storno 589179 589179 0 0.00 0 53412 111 0 0
sp_lock_selected_doc 20576 20576 0 0.00 0 9027 9 0 0
sp_make_cost_storno 27555 27555 0 0.00 0 12788 77 0 0
sp_multiply_rows_for_pdistr 27555 27555 0 0.00 0 8194 40 0 0
sp_multiply_rows_for_qdistr 20265 20265 0 0.00 0 11504 167 0 0
sp_pay_from_customer 6922 6922 0 0.00 5 11144 180 0 0
sp_pay_to_supplier 5818 5818 0 0.00 7 12997 196 0 0
sp_payment_common 12740 12740 0 0.00 2 7848 44 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 9601 362 0 0
sp_qd_handle_on_invoice_upd_sts 10588 10588 0 0.00 0 8272 571 0 0
sp_qd_handle_on_reserve_upd_sts 9879 9879 0 0.00 0 10759 69 0 0
sp_reserve_write_off 9879 9879 0 0.00 8 18255 285 0 0
sp_supplier_invoice 5514 5514 0 0.00 11 91490 3383 0 0
sp_supplier_order 5347 5347 0 0.00 7 38369 1498 0 0
srv_aggregate_perf_data 113 113 0 0.00 2188 206222 15955 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 799 125915 32160 0 0
srv_find_qd_qs_mism 1056245 1056245 0 0.00 0 10902 11 0 0
srv_make_invnt_saldo 458 458 0 0.00 45 118445 4805 0 0
srv_make_money_saldo 953 953 0 0.00 1 13095 418 0 0
srv_recalc_idx_stat 5 5 0 0.00 47522 491217 255237 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 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 4991 4991 0 0.00 0 13514 1019 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 8022 483 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 1073 24 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 1302 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 2949 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 2963 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 1158 43 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 1 125165 7897 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 1529 22 0 0
t$perf-norm:sp_client_order 20265 20265 0 0.00 0 8667 40 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 915 15 0 0
t$perf-norm:sp_pay_from_customer 6922 6922 0 0.00 0 9193 20 0 0
t$perf-norm:sp_pay_to_supplier 5818 5818 0 0.00 0 16244 23 0 0
t$perf-norm:sp_reserve_write_off 9879 9879 0 0.00 0 8702 26 0 0
t$perf-norm:sp_supplier_invoice 5514 5514 0 0.00 0 9346 36 0 0
t$perf-norm:sp_supplier_order 5347 5347 0 0.00 0 6427 35 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 349 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 153 5 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 867 27 0 0
t$perf-norm:srv_make_money_saldo 953 953 0 0.00 0 3844 12 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 19 297 123 0 0
v_add_invoice_to_stock 10046 9864 182 1.81 0 4784 28 0 182
v_all_customers 36983 36983 0 0.00 0 124 4 0 0
v_all_suppliers 30352 30352 0 0.00 0 176 5 0 0
v_all_wares 40530 40530 0 0.00 0 145 4 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 1112 17 0 0
v_cancel_client_order 3534 3534 0 0.00 0 3771 12 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 2933 14 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 1031 13 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 1510 16 0 0
v_cancel_supplier_invoice 740 726 14 1.89 0 3460 28 0 14
v_cancel_supplier_order 848 848 0 0.00 0 2398 15 0 0
v_cancel_write_off 2026 2026 0 0.00 0 2729 28 0 0
v_max_id_clo_ord 5344 5344 0 0.00 0 290 5 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 2162 14 0 0
v_max_id_ord_sup 5508 5508 0 0.00 0 5808 8 0 0
v_max_non_paid_invoice 1494 1494 0 0.00 0 202 8 0 0
v_max_non_paid_realizn 3675 3675 0 0.00 0 3871 9 0 0
v_min_id_clo_ord 5344 5344 0 0.00 0 957 9 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 1091 10 0 0
v_min_id_ord_sup 5508 5508 0 0.00 0 3943 10 0 0
v_min_non_paid_invoice 5818 5818 0 0.00 0 7839 14 0 0
v_min_non_paid_realizn 6922 6922 0 0.00 0 7526 14 0 0
v_our_firm 10040 10040 0 0.00 0 247 5 0 0
v_reserve_write_off 19758 19758 0 0.00 0 3528 11 0 0
x_lock_depdocs_on_canc_invoice 11541 11541 0 0.00 0 12426 129 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 10072 397 0 0
x_make_qstorno_1000_1200 5347 5347 0 0.00 2 38210 1323 0 0
x_make_qstorno_1200_2000 5514 5514 0 0.00 5 90809 3150 0 0
x_make_qstorno_2100_3300 470081 470081 0 0.00 0 50700 233 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-12-2022 07:38:47 to 23-12-2022 07:38:51.

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
05:07:26 2 8192000000 8797047104 8807632896 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:27 23533 8192000000 9721331712 10024730624 800793536 8.904 100 82 165 0 828167680 7500656 19873488
05:09:59 20089 8192000000 9771700928 10080059392 842859792 9.328 100 92 184 0 878622640 13619472 22143376
05:11:26 17398 8192000000 9808018192 10127175680 874070304 9.641 100 88 177 0 917143488 22353856 20719328
05:12:50 12511 8192000000 9860623392 10196037632 917458736 10.071 100 69 135 0 974785120 42839040 14487344
05:14:04 3848 8192000000 9919770880 10266722304 956730912 10.457 100 88 172 0 1033183328 57061584 19390832
05:15:11 2963 8192000000 9950313584 10304749568 993024096 10.811 100 84 162 0 1058370192 48019264 17326832
05:16:17 2132 8192000000 10002799520 10362183680 1021823680 11.090 100 91 182 0 1118476848 77856912 18796256
05:17:21 1037 8192000000 9998847200 10366472192 1035940960 11.226 100 90 171 0 1120550752 68083856 16525936
05:18:26 1126 8192000000 10022562912 10399522816 1060538768 11.462 100 96 190 1 1144346128 65625696 18181664
05:19:28 1005 8192000000 10054285712 10436427776 1067691648 11.530 100 94 184 1 1178658896 92437392 18529856
05:20:30 1290 8192000000 10041853296 10419478528 1074662128 11.597 100 95 173 0 1161085216 69793856 16629232
05:21:33 957 8192000000 10044309984 10427273216 1082908000 11.675 100 86 169 1 1165385632 64978336 17499296
05:22:34 999 8192000000 10049865552 10439409664 1089929056 11.742 100 78 155 3 1175705056 70075328 15700672
05:23:37 1092 8192000000 10055197408 10441097216 1091993872 11.762 100 96 178 2 1177207488 66035792 19177824
05:24:40 1127 8192000000 10060630528 10451030016 1096061472 11.800 100 98 186 0 1172300816 58146272 18093072
05:25:42 6694 8192000000 10052751840 10450341888 1098531664 11.824 100 62 122 1 1167390816 56519632 12339520
05:26:52 1051 8192000000 10061987248 10453278720 1100848432 11.846 100 88 169 3 1186427568 66918640 18660496
05:27:54 938 8192000000 10063182736 10454646784 1102432240 11.861 100 91 176 2 1182279344 61345120 18501984
05:28:56 964 8192000000 10036080704 10426564608 1102710976 11.863 100 81 156 0 1156360224 37870288 15778960
05:29:57 1090 8192000000 10056789696 10443984896 1088039216 11.724 100 95 182 1 1175481184 68196960 19245008
05:31:00 1140 8192000000 10034571408 10420465664 1085967408 11.704 100 97 180 0 1161271504 56606912 18697184
05:32:02 1020 8192000000 10020943696 10400595968 1059663120 11.453 99 93 171 3 1138501232 60832880 18005232
05:33:04 1228 8192000000 10017104640 10395058176 1057821456 11.436 99 95 175 0 1134331920 58651840 17858624
05:34:06 1129 8192000000 9975943760 10344669184 1009814544 10.974 99 85 164 1 1097268336 71532080 15921712
05:35:09 2757 8192000000 9989090320 10367127552 1041768448 11.282 100 78 147 2 1111849648 55153600 14927600
05:36:12 1158 8192000000 9994065632 10370199552 1030705472 11.175 100 87 169 4 1117300688 69373440 17221776
05:37:15 1031 8192000000 9980513216 10351951872 1034138672 11.208 100 93 179 1 1103039776 51239392 17661712
05:38:23 13973 8192000000 9990510592 10370686976 1030650336 11.175 98 68 130 0 1101514736 57867008 12997392
05:40:05 4101 8192000000 10030171664 10412937216 1069224480 11.545 100 78 145 1 1144154608 60877488 14052640
05:41:19 7733 8192000000 10093386432 10479702016 1083607104 11.682 100 72 141 2 1208887744 110684784 14595856
05:42:30 10299 8192000000 10096202848 10491740160 1103374368 11.870 100 60 116 1 1215392288 99964480 12053440
05:43:52 1546 8192000000 10160043472 10549342208 1118289248 12.011 100 83 161 0 1280318560 145395952 16633360
05:44:56 2328 8192000000 10210793584 10608480256 1129558672 12.117 100 91 174 3 1325124544 176158832 19407040
05:46:00 2069 8192000000 10246184656 10640187392 1127329280 12.096 100 94 181 0 1350047232 201909392 20808560
05:47:06 2191 8192000000 10246840480 10646642688 1147303712 12.284 100 77 150 2 1360656208 197192832 16159664
05:48:15 1070 8192000000 10304807056 10702548992 1162439744 12.426 100 95 187 3 1415881408 232583280 20858384
05:49:20 3800 8192000000 10317623744 10722045952 1164451552 12.445 100 78 155 0 1430535536 248843008 17240976
05:50:27 1417 8192000000 10386877840 10790858752 1174293760 12.537 100 93 185 0 1500272544 304700544 21278240
05:51:32 1875 8192000000 10417945232 10829807616 1173663040 12.531 100 88 174 1 1532233504 338184320 20386144
05:52:41 1710 8192000000 10438396256 10839646208 1174196384 12.536 100 95 179 1 1543199280 349146112 19856784
05:53:46 901 8192000000 10441033808 10856849408 1182586048 12.614 100 97 191 0 1557522976 352123760 22813168
05:54:51 1684 8192000000 10461453072 10891399168 1184201248 12.629 100 81 161 0 1572356208 368893264 19261696
05:55:58 778 8192000000 10493839008 10920460288 1188574432 12.670 100 97 184 2 1599888080 390518048 20795600
05:57:02 958 8192000000 10492406512 10933510144 1187263952 12.658 100 97 185 2 1601244752 390620096 23360704
05:58:08 1375 8192000000 10523393744 10973474816 1191730528 12.699 100 86 168 1 1626740704 415399680 19610496
05:59:14 1952 8192000000 10527730448 10958274560 1189562640 12.679 100 88 174 1 1636337344 425993232 20781472
06:00:20 12199 8192000000 10483037152 10931290112 1178772000 12.579 100 43 84 1 1599064592 409500128 10792464
06:01:36 890 8192000000 10472785840 10888200192 1166552928 12.465 99 93 178 0 1576916400 388853504 21509968
06:02:41 4048 8192000000 10475546320 10918711296 1175258176 12.546 100 64 123 3 1580926624 390524016 15144432
06:03:53 6292 8192000000 10519613376 10938998784 1155621232 12.362 99 78 150 1 1612089008 438959488 17508288
06:05:05 2797 8192000000 10473142640 10980966400 1164814528 12.448 100 82 151 1 1576823904 394317152 17692224
06:06:13 2047 8192000000 10528300336 10947497984 1185506448 12.642 100 78 149 1 1635300144 431390864 18402832
06:07:17 1896 8192000000 10439320928 10953572352 1166701008 12.466 99 79 148 2 1542345056 358654032 16990016
06:08:21 3399 8192000000 10456500416 10932719616 1181149776 12.601 100 87 171 2 1552878304 351316016 20412512
06:09:27 8427 8192000000 10447387136 10919358464 1179902032 12.589 100 56 112 1 1537019600 342784368 14333200
06:11:16 32014 8192000000 10331981504 10859020288 1166406224 12.463 100 31 62 0 1491478976 316684480 8388272
06:12:49 33999 8192000000 10285721104 10827145216 1139727136 12.213 99 19 38 0 1409435440 264712848 4995456
06:14:24 81829 8192000000 10115169040 10612314112 1113033584 11.961 97 9 18 0 1226486912 111435568 2017760
06:16:46 45185 8192000000 10077384176 10506092544 1146651184 12.278 100 40 76 0 1179094816 23895776 8547856
06:18:35 82522 8192000000 10044235856 10526158848 1099272464 11.831 96 10 18 0 1140923680 40282272 1368944
06:20:59 125915 8192000000 10074220752 10528083968 1103696064 11.873 96 40 81 0 1164231152 52484880 8050208
06:24:17 73798 8192000000 10085749808 10538545152 1127433072 12.097 99 27 54 1 1177898496 44242800 6222624
06:26:39 45349 8192000000 10128817408 10552094720 1138305232 12.200 99 86 158 0 1228897552 71715152 18877168
06:28:26 17173 8192000000 10135741808 10573471744 1138941760 12.206 100 46 92 0 1243603136 94360384 10300992
06:29:47 16371 8192000000 10148960880 10584915968 1127927344 12.102 98 55 104 2 1236453456 96678512 11847600
06:31:06 23797 8192000000 10221419008 10684067840 1164985936 12.450 100 41 82 0 1302928000 127868976 10073088
06:32:34 62620 8192000000 10178459040 10638770176 1117189584 12.000 96 18 36 0 1266087696 144284720 4613392
06:34:40 43732 8192000000 10217141648 10691526656 1140168704 12.217 100 24 48 1 1343188464 197033680 5986080
06:36:38 57385 8192000000 10196980512 10746757120 1132049216 12.141 99 17 32 0 1340279712 204519152 3711344
06:38:39 21403 8192000000 10074723904 10505052160 1139249008 12.208 100 48 90 0 1164109920 15952528 8908384
06:40:01 36360 8192000000 10107542064 10548453376 1142135136 12.236 100 24 43 0 1204653696 58222288 4296272
06:41:47 38927 8192000000 10111658192 10563911680 1118080976 12.009 97 28 54 0 1181849136 58049008 5719152
06:43:40 36401 8192000000 10201687808 10633654272 1156430432 12.370 100 49 94 0 1279015280 112901568 9683280
06:45:17 47814 8192000000 10122834720 10626588672 1129766448 12.119 98 20 38 0 1258541008 124892384 3882176
06:47:06 34015 8192000000 10119749376 10546597888 1141298000 12.228 99 58 106 3 1201037552 48556240 11183312
06:48:46 22573 8192000000 10111398640 10551447552 1132410112 12.144 100 39 74 1 1192735152 52817184 7507856
06:50:10 73696 8192000000 10091736704 10578694144 1139119776 12.207 100 20 40 0 1214192800 70861872 4211152
06:53:11 85405 8192000000 10074218832 10510983168 1094553488 11.786 96 35 70 0 1155202144 53428704 7219952
06:55:40 22427 8192000000 10158979680 10595778560 1134178656 12.161 100 46 92 1 1245304368 101835200 9290512
06:57:07 22096 8192000000 10114117744 10556882944 1116925216 11.998 98 28 55 0 1208453248 85298816 6229216
06:58:31 70233 8192000000 10090651312 10582003712 1086672928 11.711 96 17 35 0 1172492736 82204848 3614960
07:02:34 38626 8192000000 10058897328 10527125504 1116758256 11.996 100 26 49 2 1173513824 51657008 5098560
07:04:18 23256 8192000000 10068364000 10503647232 1121004288 12.036 100 29 56 0 1170244368 43387488 5852592
07:05:41 95345 8192000000 10100934000 10594373632 1085288624 11.698 96 16 32 0 1149748496 60268352 4191520
07:08:17 48751 8192000000 10120877376 10558939136 1119785872 12.025 99 20 39 0 1210108880 86477120 3845888
07:10:12 35226 8192000000 10111865968 10582290432 1140530752 12.221 100 34 67 0 1230634752 83773088 6330912
07:11:51 56213 8192000000 10084938656 10516611072 1083927616 11.685 95 38 74 1 1148883568 57461680 7494272
07:13:48 27463 8192000000 10099405584 10528567296 1114573712 11.976 98 44 86 0 1161839040 37887104 9378224
07:15:20 40294 8192000000 10109341712 10576834560 1091845264 11.760 96 32 64 0 1197490672 98859696 6785712
07:17:03 102761 8192000000 10064605696 10533150720 1101724992 11.854 97 24 48 0 1138063552 31596848 4741712
07:19:47 35615 8192000000 10171888544 10633904128 1136050304 12.178 100 47 94 0 1247419936 101519376 9850256
07:21:41 59686 8192000000 10087119936 10558324736 1134046400 12.159 100 11 22 0 1216268480 79606240 2615840
07:23:41 54387 8192000000 10071607408 10541334528 1137193264 12.189 100 12 22 0 1182353648 42753552 2406832
07:25:35 91408 8192000000 10104104704 10555691008 1127583072 12.099 99 8 16 0 1158656128 29492528 1580528
07:28:10 51082 8192000000 10104138800 10530758656 1123655520 12.062 99 36 69 0 1160817296 29454688 7707088
07:30:03 41300 8192000000 10114977968 10568364032 1137624288 12.193 99 22 43 0 1213762272 71369424 4768560
07:31:46 70448 8192000000 10173316944 10614878208 1120868256 12.035 97 26 52 1 1219662080 93243488 5550336
07:33:58 52484 8192000000 10208736144 10643304448 1099886400 11.837 96 33 66 0 1258011200 150623664 7501136
07:35:57 34112 8192000000 10201530736 10717708288 1137012496 12.187 100 32 64 0 1284614560 140829376 6772688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 23-12-2022 07:38:53 to 23-12-2022 07:39:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 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 23-12-2022 07:39:11 to 23-12-2022 07:39:13.

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          213152
MON$OLDEST_ACTIVE               219064
MON$OLDEST_SNAPSHOT             219064
MON$NEXT_TRANSACTION            219064
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                       2087520
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             771
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 ---
:350: 
:351: mscs-test-161.budzdorov.ru    Fri Dec 23 07:37:43 2022
:352:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:353: 
:354: 
:355: mscs-test-161.budzdorov.ru    Fri Dec 23 07:37:45 2022
:356:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:357: 
:358: 
:359: mscs-test-161.budzdorov.ru    Fri Dec 23 07:37:46 2022
:360:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:361: 
:362: 
:363: mscs-test-161.budzdorov.ru    Fri Dec 23 07:37:46 2022
:364:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:365: 
:366: 
:367: mscs-test-161.budzdorov.ru    Fri Dec 23 07:37:46 2022
:368:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:369: 
--- end of diff output ---

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

23.12.22 07:39:14. 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-23 07:39:16 No severe PSQL-related problems occured
23.12.22 07:39:18. End of report.