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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-26 01:38:30
DTS_END                         2022-12-26 01:38:30.3810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(751:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53448
TRN_ID                          233378
ATT_ID                          691
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: 9206.29 22164 1104755
customer order: creation 163.15 385 19578
customer order: refuse 13.80 337 1656
order to supplier: creation 43.73 1228 5247
order to supplier: removal 3.60 106388 432
invoice (draft): creation 43.35 3231 5202
invoice (draft): removal 54.30 810 6516
invoice accept: apply 40.06 16297 4807
invoice accept: cancel 48.73 697 5847
customer reserve: creation 3728.36 161 447403
customer reserve: removal 4777.64 63 573317
realization accept: apply 79.22 170 9506
realization accept: cancel 86.63 109 10395
payment from customer: creation 56.51 92 6781
payment from customer: removal 5.38 97 646
payment to supplier: creation 47.13 109 5655
payment to supplier: removal 3.38 60 405
service: total inventory turnovers 4.43 1210 531
service: total monetary turnovers 6.12 59 734
service: aggregate perf. data 0.78 16946 93
service: refresh index statistics 0.03 294837 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-12-2022 01:39:38 to 26-12-2022 01:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 567
TEST_TIME 2 2545
TEST_TIME 3 2862
TEST_TIME 4 3969
TEST_TIME 5 4540
TEST_TIME 6 4560
TEST_TIME 7 4372
TEST_TIME 8 4370
TEST_TIME 9 4656
TEST_TIME 10 4725
TEST_TIME 11 5048
TEST_TIME 12 5233
TEST_TIME 13 5390
TEST_TIME 14 5870
TEST_TIME 15 6066
TEST_TIME 16 6273
TEST_TIME 17 6380
TEST_TIME 18 6578
TEST_TIME 19 6928
TEST_TIME 20 7441
TEST_TIME 21 7768
TEST_TIME 22 8276
TEST_TIME 23 8647
TEST_TIME 24 9199
TEST_TIME 25 9979
TEST_TIME 26 10229
TEST_TIME 27 10435
TEST_TIME 28 10450
TEST_TIME 29 10236
TEST_TIME 30 10189
TEST_TIME 31 10125
TEST_TIME 32 10050
TEST_TIME 33 9892
TEST_TIME 34 9886
TEST_TIME 35 9835
TEST_TIME 36 9676
TEST_TIME 37 9743
TEST_TIME 38 9660
TEST_TIME 39 9494
TEST_TIME 40 9573
TEST_TIME 41 9518
TEST_TIME 42 9417
TEST_TIME 43 9275
TEST_TIME 44 9231
TEST_TIME 45 9208
TEST_TIME 46 9156
TEST_TIME 47 9070
TEST_TIME 48 9060
TEST_TIME 49 9050
TEST_TIME 50 9219
TEST_TIME 51 9270
TEST_TIME 52 9269
TEST_TIME 53 9213
TEST_TIME 54 9322
TEST_TIME 55 9397
TEST_TIME 56 9455
TEST_TIME 57 9483
TEST_TIME 58 9505
TEST_TIME 59 9475
TEST_TIME 60 9574
TEST_TIME 61 9602
TEST_TIME 62 9513
TEST_TIME 63 9410
TEST_TIME 64 9333
TEST_TIME 65 9295
TEST_TIME 66 9292
TEST_TIME 67 9212
TEST_TIME 68 9223
TEST_TIME 69 9126
TEST_TIME 70 9120
TEST_TIME 71 9153
TEST_TIME 72 9145
TEST_TIME 73 9188
TEST_TIME 74 9148
TEST_TIME 75 9141
TEST_TIME 76 9133
TEST_TIME 77 9209
TEST_TIME 78 9277
TEST_TIME 79 9281
TEST_TIME 80 9313
TEST_TIME 81 9375
TEST_TIME 82 9382
TEST_TIME 83 9373
TEST_TIME 84 9288
TEST_TIME 85 9272
TEST_TIME 86 9257
TEST_TIME 87 9236
TEST_TIME 88 9169
TEST_TIME 89 9116
TEST_TIME 90 9102
TEST_TIME 91 9058
TEST_TIME 92 9139
TEST_TIME 93 9171
TEST_TIME 94 9231
TEST_TIME 95 9216
TEST_TIME 96 9215
TEST_TIME 97 9207
TEST_TIME 98 9342
TEST_TIME 99 9412
TEST_TIME 100 9393
TEST_TIME 101 9480
TEST_TIME 102 9475
TEST_TIME 103 9450
TEST_TIME 104 9494
TEST_TIME 105 9508
TEST_TIME 106 9434
TEST_TIME 107 9415
TEST_TIME 108 9363
TEST_TIME 109 9345
TEST_TIME 110 9327
TEST_TIME 111 9314
TEST_TIME 112 9334
TEST_TIME 113 9320
TEST_TIME 114 9290
TEST_TIME 115 9243
TEST_TIME 116 9236
TEST_TIME 117 9260
TEST_TIME 118 9247
TEST_TIME 119 9266
TEST_TIME 120 9207

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-12-2022 01:39:41 to 26-12-2022 01:39:45.

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 1520569 1520569 0 0.00 0 18718 5 0 0
doc_list_biud 1520569 1520569 0 0.00 0 36424 30 0 0
sp_add_invoice_to_stock 4914 4807 107 2.17 26 267593 16298 0 107
sp_cancel_adding_invoice 5847 5847 0 0.00 4 58590 698 0 0
sp_cancel_client_order 1656 1656 0 0.00 4 10031 337 0 0
sp_cancel_customer_reserve 573317 573317 0 0.00 0 21676 64 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 2 9398 97 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 1547 60 0 0
sp_cancel_supplier_invoice 6527 6516 11 0.16 1 36448 811 0 11
sp_cancel_supplier_order 432 432 0 0.00 8 1113133 106388 0 0
sp_cancel_write_off 10395 10395 0 0.00 1 18484 110 0 0
sp_client_order 19578 19578 0 0.00 3 16631 386 0 0
sp_customer_reserve 447403 447403 0 0.00 0 113378 161 0 0
sp_fill_shopping_cart 30016 30016 0 0.00 1 16755 37 0 0
sp_fill_shopping_cart_clo_res 447399 447399 0 0.00 0 17177 15 0 0
sp_get_clo_for_invoice 4800 4800 0 0.00 0 29195 302 0 0
sp_kill_cost_storno 17293 17293 0 0.00 0 7448 52 0 0
sp_kill_qstorno_ret_qs2qd 1163977 1163977 0 0.00 0 36411 34 0 0
sp_kill_qty_storno 612469 612469 0 0.00 0 36417 73 0 0
sp_lock_selected_doc 19842 19842 0 0.00 0 6558 8 0 0
sp_make_cost_storno 26742 26742 0 0.00 0 8346 46 0 0
sp_multiply_rows_for_pdistr 26742 26742 0 0.00 0 8337 23 0 0
sp_multiply_rows_for_qdistr 19578 19578 0 0.00 0 16623 181 0 0
sp_pay_from_customer 6781 6781 0 0.00 4 15245 92 0 0
sp_pay_to_supplier 5655 5655 0 0.00 4 9085 110 0 0
sp_payment_common 12436 12436 0 0.00 1 15231 25 0 0
sp_qd_handle_on_cancel_clo 1656 1656 0 0.00 0 10026 299 0 0
sp_qd_handle_on_invoice_upd_sts 10647 10647 0 0.00 0 16008 284 0 0
sp_qd_handle_on_reserve_upd_sts 9506 9506 0 0.00 0 15261 68 0 0
sp_reserve_write_off 9506 9506 0 0.00 6 15309 170 0 0
sp_supplier_invoice 5202 5202 0 0.00 6 96554 3231 0 0
sp_supplier_order 5247 5247 0 0.00 5 62564 1229 0 0
srv_aggregate_perf_data 93 93 0 0.00 2370 117919 16946 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1638 118941 50269 0 0
srv_find_qd_qs_mism 1059782 1059782 0 0.00 0 18717 4 0 0
srv_make_invnt_saldo 531 531 0 0.00 36 59843 1210 0 0
srv_make_money_saldo 734 734 0 0.00 1 16077 59 0 0
srv_recalc_idx_stat 4 4 0 0.00 199083 403409 294837 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4807 4807 0 0.00 0 10635 169 0 0
t$perf-norm:sp_cancel_adding_invoice 368 368 0 0.00 0 7146 111 0 0
t$perf-norm:sp_cancel_client_order 1656 1656 0 0.00 0 6124 15 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 2147 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 5141 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 489 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 868 8 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 21720 1612 0 0
t$perf-norm:sp_cancel_write_off 959 959 0 0.00 0 1918 6 0 0
t$perf-norm:sp_client_order 19578 19578 0 0.00 0 9006 18 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 1627 8 0 0
t$perf-norm:sp_pay_from_customer 6781 6781 0 0.00 0 8474 14 0 0
t$perf-norm:sp_pay_to_supplier 5655 5655 0 0.00 0 8895 14 0 0
t$perf-norm:sp_reserve_write_off 9506 9506 0 0.00 0 8533 12 0 0
t$perf-norm:sp_supplier_invoice 5202 5202 0 0.00 0 6784 21 0 0
t$perf-norm:sp_supplier_order 5247 5247 0 0.00 0 8876 20 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 195 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 425 7 0 0
t$perf-norm:srv_make_invnt_saldo 531 531 0 0.00 0 1795 25 0 0
t$perf-norm:srv_make_money_saldo 734 734 0 0.00 0 3483 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9814 9600 214 2.18 0 9309 21 0 214
v_all_customers 36044 36044 0 0.00 0 17 0 0 0
v_all_suppliers 29438 29438 0 0.00 0 101 1 0 0
v_all_wares 39154 39154 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 736 736 0 0.00 0 2348 10 0 0
v_cancel_client_order 3312 3312 0 0.00 0 9242 8 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 9354 20 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 9243 13 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 1516 7 0 0
v_cancel_supplier_invoice 784 762 22 2.80 0 1346 16 0 22
v_cancel_supplier_order 864 864 0 0.00 0 1239 6 0 0
v_cancel_write_off 1918 1918 0 0.00 0 1232 12 0 0
v_max_id_clo_ord 5247 5247 0 0.00 0 838 1 0 0
v_max_id_clo_res 978 978 0 0.00 0 3511 24 0 0
v_max_id_ord_sup 5191 5191 0 0.00 0 6238 5 0 0
v_max_non_paid_invoice 1374 1374 0 0.00 0 2141 5 0 0
v_max_non_paid_realizn 3563 3563 0 0.00 0 675 3 0 0
v_min_id_clo_ord 5247 5247 0 0.00 0 4881 8 0 0
v_min_id_clo_res 978 978 0 0.00 0 6822 18 0 0
v_min_id_ord_sup 5191 5191 0 0.00 0 4372 5 0 0
v_min_non_paid_invoice 5655 5655 0 0.00 0 6588 19 0 0
v_min_non_paid_realizn 6781 6781 0 0.00 0 15227 21 0 0
v_our_firm 9546 9546 0 0.00 0 14 1 0 0
v_reserve_write_off 19012 19012 0 0.00 0 9220 6 0 0
x_lock_depdocs_on_canc_invoice 11982 11982 0 0.00 0 17711 114 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 7439 315 0 0
x_make_qstorno_1000_1200 5247 5247 0 0.00 1 62549 1159 0 0
x_make_qstorno_1200_2000 5202 5202 0 0.00 2 96545 3091 0 0
x_make_qstorno_2100_3300 447403 447403 0 0.00 0 113376 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-12-2022 01:39:46 to 26-12-2022 01:39:50.

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
23:08:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:34 29733 8192000000 9900803904 10208948224 981990048 10.704 100 90 180 1 1012365728 6165376 24210304
23:11:09 12462 8192000000 9927914000 10244706304 1011224992 10.987 100 89 178 0 1042752560 8146640 23380928
23:12:31 15650 8192000000 9977001632 10305978368 1057320880 11.431 100 87 175 0 1089072544 10025056 21726608
23:13:54 9015 8192000000 10027838320 10371260416 1111314832 11.945 100 80 158 1 1141942576 11884640 18743104
23:15:05 6326 8192000000 10066563568 10425475072 1158074448 12.385 100 77 154 0 1188136576 12126816 17935312
23:16:13 2858 8192000000 10110249296 10477408256 1206679584 12.838 100 72 142 1 1233911952 11986096 15246272
23:17:18 2793 8192000000 10149738944 10523639808 1237763520 13.126 100 80 157 0 1268420496 12919504 17737472
23:18:23 1577 8192000000 10185525456 10560258048 1270951280 13.430 100 88 175 1 1304581520 14134352 19495888
23:19:26 889 8192000000 10206921088 10587340800 1288487984 13.590 100 92 182 1 1322359504 14039344 19832176
23:20:28 1887 8192000000 10207375936 10597998592 1296458000 13.663 100 72 140 0 1321448992 8096304 16894688
23:21:30 4750 8192000000 10195190736 10600624128 1304311072 13.734 100 32 64 0 1319035728 7825552 6899104
23:22:37 9371 8192000000 10210806912 10612371456 1309060272 13.778 100 23 44 0 1320759936 7176880 4522784
23:23:50 13132 8192000000 10199490016 10618187776 1316646272 13.846 100 20 39 1 1327775248 6633728 4495248
23:25:07 13142 8192000000 10228559136 10631544832 1319999936 13.877 100 57 114 1 1340424800 7391104 13033760
23:26:21 5744 8192000000 10227822752 10639171584 1330215936 13.969 100 27 50 1 1342113216 5791632 6105648
23:27:29 5482 8192000000 10213452816 10635632640 1328189552 13.951 100 15 30 1 1337456176 5948848 3317776
23:28:35 60743 8192000000 10199391360 10601295872 1235334240 13.103 94 32 64 2 1249152688 5000752 8817696
23:30:38 4601 8192000000 10171194544 10577092608 1266161216 13.386 99 24 48 0 1278551568 6450176 5940176
23:31:44 6666 8192000000 10127798688 10526519296 1204015360 12.814 97 21 43 0 1214930384 5372608 5542416
23:32:53 15855 8192000000 10152867856 10548768768 1232646496 13.078 97 19 37 1 1241542112 4512384 4383232
23:34:12 29959 8192000000 10154506816 10565697536 1252679264 13.263 98 20 38 0 1262654160 5452096 4522800
23:35:46 5404 8192000000 10176206080 10589437952 1293195632 13.633 100 30 60 0 1305503808 5586528 6721648
23:36:58 14769 8192000000 10182831920 10599428096 1298726736 13.684 100 9 19 0 1310104752 9480000 1898016
23:38:14 110122 8192000000 10239878496 10654232576 1321606784 13.891 100 39 71 3 1338107376 9152336 7348256
23:41:13 7531 8192000000 10257192912 10674458624 1354395392 14.187 100 43 84 1 1372987664 9410512 9181760
23:42:27 26659 8192000000 10264343216 10691674112 1363716816 14.271 100 33 64 1 1382554240 11299392 7538032
23:43:59 16108 8192000000 10294946176 10715820032 1383570032 14.448 100 39 77 0 1405069856 12551616 8948208
23:45:21 38853 8192000000 10316378080 10742693888 1392360048 14.527 100 43 86 0 1421296656 19062544 9874064
23:47:08 6382 8192000000 10342863120 10769502208 1416892464 14.745 100 41 82 0 1454998720 27689568 10416688
23:48:18 9474 8192000000 10355767808 10782715904 1428040272 14.844 100 48 96 0 1470789792 30383536 12365984
23:49:35 5966 8192000000 10378892976 10801811456 1437140192 14.924 100 60 120 0 1489565120 37368944 15055984
23:50:45 7910 8192000000 10389305440 10814693376 1445522160 14.998 100 60 120 0 1500902624 39946400 15434064
23:52:13 8391 8192000000 10384018912 10805153792 1433212672 14.890 100 58 116 1 1491146064 42037632 15895760
23:53:31 3598 8192000000 10394223808 10814582784 1437611104 14.929 100 68 136 1 1501726384 46585760 17529520
23:54:43 1638 8192000000 10383033120 10797977600 1425436768 14.821 99 74 147 0 1489374288 45947008 17990512
23:55:52 2160 8192000000 10403328224 10816364544 1432673088 14.885 100 75 148 1 1505624192 52994560 19956544
23:57:02 6174 8192000000 10371636400 10787954688 1405031632 14.640 100 67 134 1 1482501328 58624384 18845312
23:58:16 10268 8192000000 10357203296 10780696576 1396643760 14.565 100 43 86 0 1464405424 56083344 11678320
23:59:30 7417 8192000000 10352567856 10778095616 1370045248 14.327 99 57 113 0 1448751776 63594544 15111984
00:00:42 16679 8192000000 10354701904 10789388288 1378468992 14.403 99 38 76 0 1452159216 62640176 11050048
00:02:20 19067 8192000000 10335836464 10778009600 1385221024 14.463 100 31 62 0 1445998352 51727888 9049440
00:03:40 51325 8192000000 10291220624 10736041984 1332780336 13.992 97 26 50 0 1386193392 46096160 7316896
00:05:36 35169 8192000000 10270588704 10709372928 1334557856 14.008 98 36 72 0 1353587312 10992288 8037168
00:07:12 64573 8192000000 10236030960 10680115200 1307108960 13.760 97 8 16 0 1316692336 7659568 1923808
00:09:18 48231 8192000000 10220576928 10663223296 1307215760 13.761 98 16 32 1 1319847440 9120160 3511520
00:11:18 75133 8192000000 10245221264 10682621952 1304264688 13.734 96 14 28 0 1318030672 10527200 3238784
00:13:38 79599 8192000000 10261999104 10696372224 1339621104 14.054 99 45 88 2 1358788720 7480656 11686960
00:16:19 68765 8192000000 10266015552 10693668864 1297084960 13.669 95 53 105 0 1319410400 9254448 13070992
00:18:29 40550 8192000000 10257807088 10709417984 1354189840 14.185 100 25 50 0 1374229504 14261488 5778176
00:21:05 58322 8192000000 10257425936 10711728128 1365083440 14.283 100 14 28 0 1378729296 10302720 3343136
00:23:31 32600 8192000000 10265206576 10721726464 1367844800 14.308 100 29 57 2 1387265936 13270144 6150992
00:25:25 93286 8192000000 10289019408 10757906432 1387485904 14.483 100 19 38 1 1406383824 14453456 4444464
00:28:16 56094 8192000000 10290160320 10740670464 1356816352 14.209 98 24 46 0 1386573424 24406048 5351024
00:30:16 43288 8192000000 10307911744 10765959168 1397976240 14.577 100 23 46 0 1424034352 20710096 5348016
00:32:19 67941 8192000000 10290771328 10759016448 1362351088 14.258 98 8 16 1 1379077568 14826272 1900208
00:34:28 47294 8192000000 10291394896 10759139328 1391361024 14.518 100 25 50 0 1413860784 16611488 5888272
00:36:20 64776 8192000000 10297024896 10746626048 1374963536 14.371 99 18 36 0 1391151424 12386288 3801600
00:38:26 118941 8192000000 10276026784 10732605440 1304152800 13.733 94 14 26 0 1325883888 19125856 2605232
00:41:25 106789 8192000000 10289844624 10736996352 1374551008 14.368 99 33 66 0 1389588352 7595920 7441424
00:44:14 55301 8192000000 10255986624 10716086272 1325431104 13.926 96 15 30 1 1340281968 11428352 3422512
00:46:10 54946 8192000000 10265314992 10723196928 1345025824 14.103 98 4 8 0 1357865040 12241120 598096
00:48:07 90650 8192000000 10248534608 10712723456 1305590464 13.746 95 5 9 0 1313698608 7103120 1005024
00:50:38 68223 8192000000 10265330192 10719277056 1338803536 14.047 97 17 34 0 1351099520 8701552 3594432
00:52:56 38722 8192000000 10270084800 10713698304 1333666976 14.000 97 40 80 1 1353030352 10049056 9314320
00:54:35 44584 8192000000 10267936240 10728382464 1325142704 13.923 97 20 40 0 1350550544 20602704 4805136
00:56:20 59826 8192000000 10289122720 10737532928 1364490752 14.278 99 21 41 0 1383961104 15422144 4048208
00:58:21 26098 8192000000 10271556496 10722590720 1355724464 14.199 99 10 20 0 1375144672 16902704 2517504
00:59:50 21388 8192000000 10252882032 10681294848 1305956368 13.749 97 39 76 1 1325401856 10774064 8671424
01:01:14 117514 8192000000 10260043888 10700107776 1338768048 14.046 99 28 56 0 1355016160 10081616 6166496
01:04:16 40586 8192000000 10256541328 10704703488 1344272256 14.096 99 25 50 0 1360800336 10955920 5572160
01:06:23 52099 8192000000 10258715776 10711556096 1360686688 14.244 100 12 24 0 1375634944 12189440 2758816
01:08:29 81318 8192000000 10290079504 10740350976 1365318432 14.285 99 25 50 0 1391698768 20395920 5984416
01:10:55 72547 8192000000 10260877056 10722119680 1326891648 13.939 97 13 27 0 1354707760 24940800 2875312
01:13:17 47618 8192000000 10293944384 10739728384 1365865648 14.290 99 18 36 0 1382161200 12563872 3731680
01:15:05 76684 8192000000 10302391888 10757853184 1372487056 14.349 99 15 30 1 1400698944 24642992 3568896
01:17:31 60690 8192000000 10321458864 10758795264 1396914864 14.568 100 43 83 0 1421508656 15502272 9091520
01:19:33 114579 8192000000 10300055728 10751541248 1354402384 14.187 97 15 30 0 1376933072 19307312 3223376
01:22:28 32808 8192000000 10309298256 10769362944 1396417440 14.563 100 14 28 0 1415102288 15485552 3199296
01:24:01 84712 8192000000 10305542736 10752958464 1386283632 14.473 99 12 24 0 1403852944 15241424 2327888
01:27:23 56515 8192000000 10295412336 10752487424 1374841504 14.370 98 27 54 0 1392785776 11820688 6123584
01:30:05 52453 8192000000 10292927136 10751320064 1382104496 14.435 99 13 26 0 1398518016 13422464 2991056
01:32:01 97935 8192000000 10288104256 10743287808 1356964080 14.210 97 11 22 1 1369169456 9894752 2310624
01:34:40 86315 8192000000 10285071744 10741334016 1345549632 14.107 97 10 20 0 1358059808 10292736 2217440
01:37:11 75155 8192000000 10287292912 10730459136 1324614720 13.918 95 32 60 1 1338972160 7932336 6425104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-12-2022 01:39:52 to 26-12-2022 01:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-12-2022 01:40:08 to 26-12-2022 01:40:10.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          233377
MON$OLDEST_ACTIVE               234887
MON$OLDEST_SNAPSHOT             234887
MON$NEXT_TRANSACTION            234887
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2005568
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
:667: 
:668: mscs-test-161.budzdorov.ru    Mon Dec 26 01:38:31 2022
:669:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:670: 
:671: 
:672: mscs-test-161.budzdorov.ru    Mon Dec 26 01:38:32 2022
:673:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:674: 
:675: 
:676: mscs-test-161.budzdorov.ru    Mon Dec 26 01:38:33 2022
:677:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:678: 
:679: 
:680: mscs-test-161.budzdorov.ru    Mon Dec 26 01:38:34 2022
:681:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:682: 
--- end of diff output ---

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

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