Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.12.2022 22: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 15203304 kB
MemAvailable 30056436 kB
Buffers 0 kB
Cached 16496372 kB
SwapCached 3452 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-18 22:38:30
DTS_END                         2022-12-18 22:38:30.7270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57450
TRN_ID                          236350
ATT_ID                          675
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: 9182.41 25472 1101889
customer order: creation 168.00 345 20160
customer order: refuse 14.53 395 1743
order to supplier: creation 45.96 1158 5515
order to supplier: removal 3.47 99961 416
invoice (draft): creation 45.58 3231 5470
invoice (draft): removal 52.51 794 6301
invoice accept: apply 41.01 18158 4921
invoice accept: cancel 47.52 881 5702
customer reserve: creation 3847.09 176 461651
customer reserve: removal 4612.77 58 553532
realization accept: apply 83.03 176 9963
realization accept: cancel 91.37 106 10964
payment from customer: creation 58.97 110 7076
payment from customer: removal 5.55 70 666
payment to supplier: creation 49.13 116 5896
payment to supplier: removal 3.28 95 393
service: total inventory turnovers 4.57 1279 548
service: total monetary turnovers 7.13 51 856
service: aggregate perf. data 0.93 16874 112
service: refresh index statistics 0.03 365413 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-12-2022 22:39:44 to 18-12-2022 22:39:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3934
TEST_TIME 2 4661
TEST_TIME 3 4675
TEST_TIME 4 4111
TEST_TIME 5 4235
TEST_TIME 6 4597
TEST_TIME 7 5046
TEST_TIME 8 5570
TEST_TIME 9 5850
TEST_TIME 10 5921
TEST_TIME 11 5946
TEST_TIME 12 6329
TEST_TIME 13 6656
TEST_TIME 14 7175
TEST_TIME 15 7232
TEST_TIME 16 7478
TEST_TIME 17 7667
TEST_TIME 18 7935
TEST_TIME 19 8046
TEST_TIME 20 8517
TEST_TIME 21 8421
TEST_TIME 22 8488
TEST_TIME 23 8602
TEST_TIME 24 9096
TEST_TIME 25 9033
TEST_TIME 26 9455
TEST_TIME 27 10451
TEST_TIME 28 10848
TEST_TIME 29 10561
TEST_TIME 30 10375
TEST_TIME 31 10344
TEST_TIME 32 10169
TEST_TIME 33 9987
TEST_TIME 34 9848
TEST_TIME 35 10081
TEST_TIME 36 9971
TEST_TIME 37 9835
TEST_TIME 38 9698
TEST_TIME 39 9599
TEST_TIME 40 9522
TEST_TIME 41 9706
TEST_TIME 42 9602
TEST_TIME 43 9581
TEST_TIME 44 9477
TEST_TIME 45 9357
TEST_TIME 46 9236
TEST_TIME 47 9358
TEST_TIME 48 9260
TEST_TIME 49 9103
TEST_TIME 50 9220
TEST_TIME 51 9290
TEST_TIME 52 9251
TEST_TIME 53 9319
TEST_TIME 54 9375
TEST_TIME 55 9347
TEST_TIME 56 9499
TEST_TIME 57 9568
TEST_TIME 58 9518
TEST_TIME 59 9636
TEST_TIME 60 9592
TEST_TIME 61 9630
TEST_TIME 62 9662
TEST_TIME 63 9633
TEST_TIME 64 9495
TEST_TIME 65 9450
TEST_TIME 66 9389
TEST_TIME 67 9341
TEST_TIME 68 9277
TEST_TIME 69 9318
TEST_TIME 70 9258
TEST_TIME 71 9307
TEST_TIME 72 9266
TEST_TIME 73 9200
TEST_TIME 74 9168
TEST_TIME 75 9352
TEST_TIME 76 9311
TEST_TIME 77 9287
TEST_TIME 78 9319
TEST_TIME 79 9386
TEST_TIME 80 9422
TEST_TIME 81 9429
TEST_TIME 82 9409
TEST_TIME 83 9339
TEST_TIME 84 9289
TEST_TIME 85 9309
TEST_TIME 86 9285
TEST_TIME 87 9265
TEST_TIME 88 9257
TEST_TIME 89 9220
TEST_TIME 90 9202
TEST_TIME 91 9215
TEST_TIME 92 9177
TEST_TIME 93 9199
TEST_TIME 94 9197
TEST_TIME 95 9228
TEST_TIME 96 9228
TEST_TIME 97 9268
TEST_TIME 98 9310
TEST_TIME 99 9317
TEST_TIME 100 9356
TEST_TIME 101 9340
TEST_TIME 102 9404
TEST_TIME 103 9383
TEST_TIME 104 9389
TEST_TIME 105 9421
TEST_TIME 106 9423
TEST_TIME 107 9407
TEST_TIME 108 9339
TEST_TIME 109 9333
TEST_TIME 110 9311
TEST_TIME 111 9297
TEST_TIME 112 9264
TEST_TIME 113 9243
TEST_TIME 114 9208
TEST_TIME 115 9183
TEST_TIME 116 9176
TEST_TIME 117 9133
TEST_TIME 118 9153
TEST_TIME 119 9241
TEST_TIME 120 9191

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-12-2022 22:39:47 to 18-12-2022 22:39:51.

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 1526927 1526927 0 0.00 0 21615 6 0 0
doc_list_biud 1526926 1526926 0 0.00 0 60611 27 0 0
sp_add_invoice_to_stock 5014 4921 93 1.85 24 181892 18159 0 93
sp_cancel_adding_invoice 5702 5702 0 0.00 3 74435 882 0 0
sp_cancel_client_order 1743 1743 0 0.00 3 11220 395 0 0
sp_cancel_customer_reserve 553532 553532 0 0.00 0 60613 59 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 2 3806 71 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 5196 95 0 0
sp_cancel_supplier_invoice 6307 6301 6 0.09 2 17614 794 0 6
sp_cancel_supplier_order 416 416 0 0.00 7 1081107 99962 0 0
sp_cancel_write_off 10964 10964 0 0.00 1 11596 107 0 0
sp_client_order 20160 20160 0 0.00 3 23179 346 0 0
sp_customer_reserve 461651 461651 0 0.00 0 111164 177 0 0
sp_fill_shopping_cart 31144 31144 0 0.00 1 16830 39 0 0
sp_fill_shopping_cart_clo_res 461636 461636 0 0.00 0 11561 14 0 0
sp_get_clo_for_invoice 4900 4900 0 0.00 0 32859 348 0 0
sp_kill_cost_storno 17725 17725 0 0.00 0 8603 53 0 0
sp_kill_qstorno_ret_qs2qd 1124745 1124745 0 0.00 0 60609 32 0 0
sp_kill_qty_storno 593520 593520 0 0.00 0 60611 69 0 0
sp_lock_selected_doc 20551 20551 0 0.00 0 8352 6 0 0
sp_make_cost_storno 27834 27834 0 0.00 0 9935 49 0 0
sp_multiply_rows_for_pdistr 27834 27834 0 0.00 0 8595 29 0 0
sp_multiply_rows_for_qdistr 20160 20160 0 0.00 0 23162 157 0 0
sp_pay_from_customer 7076 7076 0 0.00 4 9591 111 0 0
sp_pay_to_supplier 5896 5896 0 0.00 5 14951 117 0 0
sp_payment_common 12972 12972 0 0.00 1 13450 25 0 0
sp_qd_handle_on_cancel_clo 1743 1743 0 0.00 0 11212 358 0 0
sp_qd_handle_on_invoice_upd_sts 10601 10601 0 0.00 0 26801 340 0 0
sp_qd_handle_on_reserve_upd_sts 9963 9963 0 0.00 0 13569 64 0 0
sp_reserve_write_off 9963 9963 0 0.00 5 13592 176 0 0
sp_supplier_invoice 5470 5470 0 0.00 6 89679 3231 0 0
sp_supplier_order 5515 5515 0 0.00 5 78446 1158 0 0
srv_aggregate_perf_data 112 112 0 0.00 2873 144279 16874 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2272 120406 47743 0 0
srv_find_qd_qs_mism 1053213 1053213 0 0.00 0 21591 5 0 0
srv_make_invnt_saldo 548 548 0 0.00 48 91571 1280 0 0
srv_make_money_saldo 856 856 0 0.00 0 18194 51 0 0
srv_recalc_idx_stat 4 4 0 0.00 158630 606523 365413 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4921 4921 0 0.00 0 28366 195 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 1 1275 78 0 0
t$perf-norm:sp_cancel_client_order 1743 1743 0 0.00 0 3901 20 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 4568 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 4245 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 1826 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 4482 32 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 29355 1808 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 5676 10 0 0
t$perf-norm:sp_client_order 20160 20160 0 0.00 0 19849 21 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 19852 43 0 0
t$perf-norm:sp_pay_from_customer 7076 7076 0 0.00 0 19851 11 0 0
t$perf-norm:sp_pay_to_supplier 5896 5896 0 0.00 0 6798 14 0 0
t$perf-norm:sp_reserve_write_off 9963 9963 0 0.00 0 17575 16 0 0
t$perf-norm:sp_supplier_invoice 5470 5470 0 0.00 0 5545 16 0 0
t$perf-norm:sp_supplier_order 5515 5515 0 0.00 0 8496 17 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 762 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 558 10 0 0
t$perf-norm:srv_make_invnt_saldo 548 548 0 0.00 0 1185 23 0 0
t$perf-norm:srv_make_money_saldo 856 856 0 0.00 0 798 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 10 5 0 0
v_add_invoice_to_stock 9984 9798 186 1.86 0 4087 16 0 186
v_all_customers 37096 37096 0 0.00 0 96 1 0 0
v_all_suppliers 30658 30658 0 0.00 0 22 1 0 0
v_all_wares 40318 40318 0 0.00 0 76 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 4277 19 0 0
v_cancel_client_order 3486 3486 0 0.00 0 3536 7 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 2500 7 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 1324 5 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 2509 10 0 0
v_cancel_supplier_invoice 798 786 12 1.50 0 1605 15 0 12
v_cancel_supplier_order 832 832 0 0.00 0 1215 8 0 0
v_cancel_write_off 1978 1978 0 0.00 0 3515 15 0 0
v_max_id_clo_ord 5515 5515 0 0.00 0 734 1 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 2854 17 0 0
v_max_id_ord_sup 5470 5470 0 0.00 0 7765 10 0 0
v_max_non_paid_invoice 1552 1552 0 0.00 0 327 3 0 0
v_max_non_paid_realizn 3650 3650 0 0.00 0 1563 4 0 0
v_min_id_clo_ord 5515 5515 0 0.00 0 2839 5 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 8029 13 0 0
v_min_id_ord_sup 5470 5470 0 0.00 0 4166 8 0 0
v_min_non_paid_invoice 5896 5896 0 0.00 0 13446 16 0 0
v_min_non_paid_realizn 7076 7076 0 0.00 0 9486 19 0 0
v_our_firm 10074 10074 0 0.00 0 36 1 0 0
v_reserve_write_off 19926 19926 0 0.00 0 3263 7 0 0
x_lock_depdocs_on_canc_invoice 11610 11610 0 0.00 0 16372 117 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 20369 395 0 0
x_make_qstorno_1000_1200 5515 5515 0 0.00 1 78439 1085 0 0
x_make_qstorno_1200_2000 5470 5470 0 0.00 3 89559 3082 0 0
x_make_qstorno_2100_3300 461650 461650 0 0.00 0 110282 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-12-2022 22:39:52 to 18-12-2022 22:39:56.

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
20:08:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:36 35818 8192000000 9904583136 10210242560 971867488 10.605 100 82 164 0 999059184 5419104 21772592
20:11:19 7125 8192000000 9941604256 10258481152 1023876624 11.109 100 94 188 0 1056394368 8047472 24470272
20:12:34 14853 8192000000 9964778880 10294616064 1051757472 11.378 100 78 156 0 1079616112 8492224 19366416
20:13:57 15078 8192000000 10033218416 10380574720 1116189712 11.991 100 68 136 1 1140755376 8604128 15961536
20:15:19 7037 8192000000 10099736336 10462396416 1189101808 12.675 100 76 150 1 1216321808 10140048 17079952
20:16:29 3999 8192000000 10133050192 10502090752 1221969936 12.980 100 85 166 0 1253315040 13293936 18051168
20:17:35 2949 8192000000 10152334224 10534641664 1251146768 13.249 100 74 148 0 1280287056 13231296 15908992
20:18:41 2102 8192000000 10195190976 10575638528 1279632832 13.510 100 82 164 0 1310868672 12855536 18380304
20:19:44 2053 8192000000 10204493728 10592563200 1293155904 13.633 100 81 161 0 1323849504 12566176 18127424
20:20:48 1244 8192000000 10219759248 10605711360 1305828736 13.748 100 85 170 0 1339439680 14255488 19355456
20:21:50 1125 8192000000 10222819328 10619662336 1314379712 13.826 100 73 146 0 1341168672 9402704 17386256
20:22:52 4918 8192000000 10212182192 10621177856 1322497248 13.899 100 38 77 3 1338712928 7516048 8699632
20:23:59 34575 8192000000 10214832704 10639831040 1326469600 13.935 100 32 64 0 1343820032 10130096 7220336
20:25:35 7626 8192000000 10222630144 10643603456 1334926528 14.012 100 26 53 0 1347226064 6608416 5691120
20:26:43 8529 8192000000 10226619648 10645520384 1336751808 14.028 100 18 37 0 1346786240 5787776 4246656
20:27:54 63251 8192000000 10243676704 10651889664 1330891664 13.975 99 41 83 0 1345894096 4966544 10035888
20:29:57 3424 8192000000 10213384400 10623328256 1310102176 13.787 99 30 58 2 1322601968 5390544 7109248
20:31:03 5050 8192000000 10165233360 10570108928 1250084416 13.239 97 17 35 0 1259582400 5572688 3925296
20:32:10 34830 8192000000 10141926928 10537103360 1187803104 12.663 94 31 62 0 1199748928 5029328 6916496
20:33:49 6504 8192000000 10139732032 10536951808 1238273280 13.130 99 25 50 0 1250177536 6474320 5429936
20:34:56 7323 8192000000 10166765216 10557046784 1258582880 13.317 99 35 66 1 1280215584 13026112 8606592
20:36:07 8765 8192000000 10194356560 10598203392 1295811648 13.657 100 48 95 0 1312950272 5909808 11228816
20:37:17 9637 8192000000 10177618144 10595012608 1294757152 13.648 100 19 38 0 1305172288 5328048 5087088
20:38:27 4379 8192000000 10215737584 10626875392 1319237904 13.870 100 31 60 1 1333142512 6857024 7047584
20:39:37 5306 8192000000 10241343248 10650157056 1339079264 14.049 100 41 82 0 1358863920 11203952 8580704
20:40:54 19670 8192000000 10273939120 10686701568 1359929488 14.237 100 58 116 0 1388921744 16028304 12963952
20:42:19 60382 8192000000 10276057856 10699243520 1369232560 14.320 100 31 62 0 1391707712 15381456 7093696
20:44:30 6152 8192000000 10292316128 10713567232 1376712192 14.387 100 47 91 3 1412137056 24335072 11089792
20:45:44 10834 8192000000 10309651392 10724810752 1383412192 14.447 100 61 121 1 1418076032 20745216 13918624
20:47:02 2465 8192000000 10319204864 10738724864 1393080544 14.533 100 60 120 0 1428994016 20829648 15083824
20:48:09 3555 8192000000 10320912400 10743316480 1397370928 14.572 100 51 100 0 1431644848 22011504 12262416
20:49:19 6004 8192000000 10343318160 10767331328 1413526128 14.715 100 57 114 0 1456985552 29365760 14093664
20:50:31 3323 8192000000 10341252624 10758381568 1396770144 14.566 99 68 136 2 1450777312 37460480 16546688
20:51:41 6151 8192000000 10363807200 10782715904 1417767168 14.753 100 71 140 1 1470547440 35902960 16877312
20:52:52 2271 8192000000 10371010976 10786574336 1420629376 14.778 100 85 172 3 1475299136 33789648 20880112
20:53:59 2825 8192000000 10344053856 10751979520 1393368784 14.536 100 80 160 0 1453198112 39323392 20505936
20:55:08 4763 8192000000 10351306048 10767130624 1388572976 14.493 99 62 123 1 1447746464 43480512 15692976
20:56:21 6149 8192000000 10372779024 10790670336 1404982256 14.639 100 69 135 0 1466853040 44773728 17097056
20:57:31 5718 8192000000 10374796752 10793713664 1408771856 14.673 100 68 135 0 1474903344 49127088 17004400
20:58:52 3902 8192000000 10360405904 10772611072 1399187824 14.588 100 75 149 0 1464245216 46326576 18730816
20:59:59 17034 8192000000 10347754336 10783408128 1406383488 14.652 100 39 78 1 1464760576 47883488 10493600
21:01:35 9032 8192000000 10331829664 10757128192 1375551440 14.377 100 38 71 1 1436504592 50578816 10374336
21:02:54 7717 8192000000 10307906720 10735902720 1362577600 14.260 100 28 54 0 1414154944 43600464 7976880
21:04:07 31368 8192000000 10302612928 10743263232 1326435184 13.935 98 30 60 1 1391647296 56395744 8816368
21:05:48 27632 8192000000 10274719136 10723028992 1342552592 14.080 99 12 22 0 1378174496 32462816 3159088
21:07:16 80854 8192000000 10267327840 10699386880 1337717152 14.037 99 45 91 0 1361732704 13102416 10913136
21:09:42 63378 8192000000 10270666528 10720149504 1362075248 14.256 100 14 28 0 1380088560 15051616 2961696
21:11:47 15456 8192000000 10280906224 10702049280 1347190784 14.122 99 49 95 0 1370151840 12055712 10905344
21:13:14 67963 8192000000 10259797520 10714730496 1340501824 14.062 98 7 14 0 1356456320 14511936 1442560
21:15:26 120406 8192000000 10282530832 10737475584 1355618608 14.198 98 8 16 0 1375446112 18403728 1423776
21:18:27 93635 8192000000 10250400800 10712977408 1324737536 13.920 97 16 32 0 1349721360 20705328 4278496
21:21:12 72129 8192000000 10274364272 10730463232 1365319488 14.285 99 13 26 0 1378797600 10450256 3027856
21:23:28 68572 8192000000 10292814160 10746232832 1368207696 14.311 99 32 62 0 1392029792 16630944 7191152
21:25:55 54482 8192000000 10295471280 10751741952 1393835328 14.540 100 9 17 0 1413194576 17651936 1707312
21:27:49 70842 8192000000 10295838688 10772692992 1386036272 14.470 99 15 30 0 1410541936 20800224 3705440
21:30:00 41688 8192000000 10286557952 10732498944 1345686512 14.109 96 37 74 0 1363486208 9531360 8268336
21:31:46 110247 8192000000 10294399040 10750349312 1373128800 14.355 99 13 21 0 1396065216 20663840 2272576
21:34:38 116914 8192000000 10301906496 10767663104 1385713840 14.468 99 15 30 0 1402768912 13727792 3327280
21:37:52 98322 8192000000 10290882624 10738311168 1338548624 14.044 96 8 17 0 1350522384 10443472 1530288
21:40:31 45810 8192000000 10298833392 10749059072 1363482224 14.269 98 29 58 0 1381048384 11158752 6407408
21:42:18 67966 8192000000 10266707360 10710028288 1341640928 14.072 97 34 67 0 1354353296 6040512 6671856
21:44:27 79162 8192000000 10278793120 10740137984 1369924064 14.326 99 15 29 0 1382027296 9072480 3030752
21:46:46 51334 8192000000 10272556000 10733928448 1366600544 14.297 99 17 34 0 1379756576 9386512 3769520
21:48:54 39012 8192000000 10268378848 10715570176 1345511904 14.107 98 23 46 0 1359297088 8632608 5152576
21:50:48 52482 8192000000 10266980352 10703364096 1331527168 13.981 97 15 30 0 1350530272 15467232 3535872
21:53:11 74355 8192000000 10241977120 10684383232 1272037360 13.440 93 9 18 0 1287336544 13384304 1914880
21:55:41 31004 8192000000 10290726112 10722414592 1357359664 14.214 100 48 96 0 1379258896 11638976 10260256
21:57:14 82097 8192000000 10271642224 10723958784 1351312832 14.159 99 14 28 0 1367300416 12797536 3190048
21:59:36 43483 8192000000 10266102320 10708668416 1310097456 13.787 96 22 44 0 1325039888 10025328 4917104
22:01:26 118242 8192000000 10258440368 10709921792 1337453376 14.034 98 30 59 2 1356149488 11352896 7343216
22:05:31 73407 8192000000 10276402112 10735747072 1361624080 14.252 99 19 38 0 1377513120 11521744 4367296
22:08:57 77988 8192000000 10272384384 10737762304 1368419232 14.313 99 12 24 0 1379386624 7664032 3303360
22:11:15 67762 8192000000 10284271504 10735181824 1324780560 13.920 95 12 24 1 1335285680 7925984 2579136
22:13:23 41450 8192000000 10280476032 10735173632 1335551744 14.017 96 18 36 1 1353282288 13893104 3837440
22:15:25 51915 8192000000 10302640704 10740768768 1343960320 14.093 97 19 38 0 1361533200 13547024 4025856
22:17:18 79647 8192000000 10296827744 10737856512 1370615136 14.333 99 16 30 0 1385985536 12025808 3344592
22:19:41 20437 8192000000 10316421072 10763014144 1393373984 14.536 100 42 85 0 1413600736 10961856 9264896
22:21:03 56768 8192000000 10321528160 10760523776 1401852192 14.611 100 29 58 0 1421323312 13579984 5891136
22:23:02 87251 8192000000 10297345664 10760138752 1373453248 14.358 98 11 22 1 1386918336 10975584 2489504
22:25:31 113072 8192000000 10303124448 10761576448 1380809232 14.424 99 16 32 0 1394652848 10013440 3830176
22:28:51 46536 8192000000 10290366304 10747707392 1370725872 14.334 98 10 20 0 1380926848 8241600 1959376
22:30:40 71368 8192000000 10278421472 10735661056 1358121456 14.220 97 10 20 0 1373262896 12474896 2666544
22:32:52 50156 8192000000 10284899360 10734948352 1341881568 14.074 97 32 61 0 1366625888 16861760 7882560
22:34:48 41002 8192000000 10304174352 10761506816 1391274048 14.517 100 26 52 1 1414403488 16764176 6365264
22:36:35 74769 8192000000 10270807744 10741792768 1337561360 14.035 97 12 24 0 1358882928 18704800 2616768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-12-2022 22:39:58 to 18-12-2022 22:40:13.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-12-2022 22:40:14 to 18-12-2022 22:40:16.

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          235163
MON$OLDEST_ACTIVE               236803
MON$OLDEST_SNAPSHOT             236803
MON$NEXT_TRANSACTION            236803
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                       2002496
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:834: 
:835: mscs-test-161.budzdorov.ru    Sun Dec 18 22:38:31 2022
:836:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:837: 
:838: 
:839: mscs-test-161.budzdorov.ru    Sun Dec 18 22:38:32 2022
:840:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:841: 
:842: 
:843: mscs-test-161.budzdorov.ru    Sun Dec 18 22:38:33 2022
:844:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:845: 
:846: 
:847: mscs-test-161.budzdorov.ru    Sun Dec 18 22:38:37 2022
:848:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:849: 
:850: 
:851: mscs-test-161.budzdorov.ru    Sun Dec 18 22:38:37 2022
:852:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:853: 
--- end of diff output ---

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

18.12.22 22:40:17. 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-18 22:40:19 No severe PSQL-related problems occured
18.12.22 22:40:21. End of report.