Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.01.2023 19: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 15299172 kB
MemAvailable 30365816 kB
Buffers 0 kB
Cached 16405348 kB
SwapCached 4428 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 2023-01-07 19:38:31
DTS_END                         2023-01-07 19:38:31.9630
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51908
TRN_ID                          244083
ATT_ID                          625
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: 9184.26 19864 1102111
customer order: creation 178.23 371 21387
customer order: refuse 15.93 367 1911
order to supplier: creation 47.34 1311 5681
order to supplier: removal 3.74 76052 449
invoice (draft): creation 47.11 3194 5653
invoice (draft): removal 51.11 731 6133
invoice accept: apply 43.13 17816 5176
invoice accept: cancel 45.81 771 5497
customer reserve: creation 3975.58 178 477070
customer reserve: removal 4474.87 49 536984
realization accept: apply 87.53 182 10504
realization accept: cancel 80.47 103 9657
payment from customer: creation 60.91 109 7309
payment from customer: removal 5.61 88 673
payment to supplier: creation 51.26 121 6151
payment to supplier: removal 3.31 54 397
service: total inventory turnovers 4.97 1056 597
service: total monetary turnovers 6.52 40 782
service: aggregate perf. data 0.80 21564 96
service: refresh index statistics 0.03 273131 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-01-2023 19:39:50 to 07-01-2023 19:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3149
TEST_TIME 2 4546
TEST_TIME 3 4275
TEST_TIME 4 5373
TEST_TIME 5 4985
TEST_TIME 6 4955
TEST_TIME 7 5212
TEST_TIME 8 5297
TEST_TIME 9 5677
TEST_TIME 10 6219
TEST_TIME 11 6146
TEST_TIME 12 6485
TEST_TIME 13 7127
TEST_TIME 14 7480
TEST_TIME 15 7405
TEST_TIME 16 7815
TEST_TIME 17 7970
TEST_TIME 18 8146
TEST_TIME 19 8077
TEST_TIME 20 8588
TEST_TIME 21 8926
TEST_TIME 22 9187
TEST_TIME 23 9087
TEST_TIME 24 9366
TEST_TIME 25 9538
TEST_TIME 26 10019
TEST_TIME 27 10549
TEST_TIME 28 10346
TEST_TIME 29 10254
TEST_TIME 30 10173
TEST_TIME 31 10164
TEST_TIME 32 10299
TEST_TIME 33 10295
TEST_TIME 34 10074
TEST_TIME 35 9936
TEST_TIME 36 9782
TEST_TIME 37 10007
TEST_TIME 38 9889
TEST_TIME 39 9939
TEST_TIME 40 9825
TEST_TIME 41 9927
TEST_TIME 42 9859
TEST_TIME 43 9729
TEST_TIME 44 9751
TEST_TIME 45 9711
TEST_TIME 46 9668
TEST_TIME 47 9615
TEST_TIME 48 9565
TEST_TIME 49 9603
TEST_TIME 50 9641
TEST_TIME 51 9621
TEST_TIME 52 9581
TEST_TIME 53 9495
TEST_TIME 54 9534
TEST_TIME 55 9626
TEST_TIME 56 9680
TEST_TIME 57 9680
TEST_TIME 58 9675
TEST_TIME 59 9679
TEST_TIME 60 9744
TEST_TIME 61 9781
TEST_TIME 62 9825
TEST_TIME 63 9860
TEST_TIME 64 9880
TEST_TIME 65 9740
TEST_TIME 66 9662
TEST_TIME 67 9658
TEST_TIME 68 9559
TEST_TIME 69 9461
TEST_TIME 70 9491
TEST_TIME 71 9509
TEST_TIME 72 9482
TEST_TIME 73 9459
TEST_TIME 74 9416
TEST_TIME 75 9566
TEST_TIME 76 9526
TEST_TIME 77 9546
TEST_TIME 78 9516
TEST_TIME 79 9508
TEST_TIME 80 9584
TEST_TIME 81 9557
TEST_TIME 82 9545
TEST_TIME 83 9597
TEST_TIME 84 9556
TEST_TIME 85 9499
TEST_TIME 86 9453
TEST_TIME 87 9432
TEST_TIME 88 9389
TEST_TIME 89 9352
TEST_TIME 90 9336
TEST_TIME 91 9359
TEST_TIME 92 9340
TEST_TIME 93 9355
TEST_TIME 94 9348
TEST_TIME 95 9337
TEST_TIME 96 9303
TEST_TIME 97 9374
TEST_TIME 98 9418
TEST_TIME 99 9399
TEST_TIME 100 9388
TEST_TIME 101 9470
TEST_TIME 102 9480
TEST_TIME 103 9461
TEST_TIME 104 9456
TEST_TIME 105 9478
TEST_TIME 106 9493
TEST_TIME 107 9472
TEST_TIME 108 9418
TEST_TIME 109 9393
TEST_TIME 110 9339
TEST_TIME 111 9311
TEST_TIME 112 9325
TEST_TIME 113 9292
TEST_TIME 114 9255
TEST_TIME 115 9265
TEST_TIME 116 9235
TEST_TIME 117 9225
TEST_TIME 118 9185
TEST_TIME 119 9242
TEST_TIME 120 9185

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-01-2023 19:39:54 to 07-01-2023 19:39:57.

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 1544080 1544080 0 0.00 0 27234 6 0 0
doc_list_biud 1544079 1544079 0 0.00 0 29026 23 0 0
sp_add_invoice_to_stock 5293 5176 117 2.21 39 171663 17816 0 117
sp_cancel_adding_invoice 5497 5497 0 0.00 4 74293 772 0 0
sp_cancel_client_order 1911 1911 0 0.00 4 11234 368 0 0
sp_cancel_customer_reserve 536984 536984 0 0.00 0 29028 50 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 4747 88 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 2 1665 54 0 0
sp_cancel_supplier_invoice 6140 6133 7 0.11 2 20072 731 0 7
sp_cancel_supplier_order 449 449 0 0.00 6 901516 76053 0 0
sp_cancel_write_off 9657 9657 0 0.00 1 20732 103 0 0
sp_client_order 21387 21387 0 0.00 3 26963 371 0 0
sp_customer_reserve 477070 477070 0 0.00 0 30727 179 0 0
sp_fill_shopping_cart 32720 32720 0 0.00 1 13582 37 0 0
sp_fill_shopping_cart_clo_res 477070 477070 0 0.00 0 12542 13 0 0
sp_get_clo_for_invoice 5175 5175 0 0.00 0 22215 270 0 0
sp_kill_cost_storno 16224 16224 0 0.00 0 6869 46 0 0
sp_kill_qstorno_ret_qs2qd 1090207 1090207 0 0.00 0 29024 27 0 0
sp_kill_qty_storno 576310 576310 0 0.00 0 29026 60 0 0
sp_lock_selected_doc 21734 21734 0 0.00 0 6929 6 0 0
sp_make_cost_storno 29139 29139 0 0.00 0 27231 52 0 0
sp_multiply_rows_for_pdistr 29139 29139 0 0.00 0 7913 30 0 0
sp_multiply_rows_for_qdistr 21387 21387 0 0.00 0 16898 152 0 0
sp_pay_from_customer 7309 7309 0 0.00 4 23722 110 0 0
sp_pay_to_supplier 6151 6151 0 0.00 5 9912 122 0 0
sp_payment_common 13460 13460 0 0.00 1 13224 25 0 0
sp_qd_handle_on_cancel_clo 1911 1911 0 0.00 0 11227 338 0 0
sp_qd_handle_on_invoice_upd_sts 10672 10672 0 0.00 0 18453 298 0 0
sp_qd_handle_on_reserve_upd_sts 10504 10504 0 0.00 0 16626 53 0 0
sp_reserve_write_off 10504 10504 0 0.00 5 16641 183 0 0
sp_supplier_invoice 5653 5653 0 0.00 6 74480 3194 0 0
sp_supplier_order 5681 5681 0 0.00 5 33734 1311 0 0
srv_aggregate_perf_data 96 96 0 0.00 2338 259557 21564 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1494 120532 46997 0 0
srv_find_qd_qs_mism 1052930 1052930 0 0.00 0 15513 5 0 0
srv_make_invnt_saldo 597 597 0 0.00 44 73989 1057 0 0
srv_make_money_saldo 782 782 0 0.00 1 10679 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 135051 452222 273131 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 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 5176 5176 0 0.00 0 18870 196 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 10751 115 0 0
t$perf-norm:sp_cancel_client_order 1911 1911 0 0.00 0 4549 14 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 821 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 6151 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 166 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 1465 10 0 0
t$perf-norm:sp_cancel_supplier_order 449 449 0 0.00 0 20269 1249 0 0
t$perf-norm:sp_cancel_write_off 1077 1077 0 0.00 0 3444 7 0 0
t$perf-norm:sp_client_order 21387 21387 0 0.00 0 15398 17 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 328 2 0 0
t$perf-norm:sp_pay_from_customer 7309 7309 0 0.00 0 8964 16 0 0
t$perf-norm:sp_pay_to_supplier 6151 6151 0 0.00 0 14418 16 0 0
t$perf-norm:sp_reserve_write_off 10504 10504 0 0.00 0 14762 15 0 0
t$perf-norm:sp_supplier_invoice 5653 5653 0 0.00 0 8704 18 0 0
t$perf-norm:sp_supplier_order 5681 5681 0 0.00 0 7123 13 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 201 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 12078 37 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 684 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10582 10348 234 2.21 0 6116 19 0 234
v_all_customers 38938 38938 0 0.00 0 17 1 0 0
v_all_suppliers 31372 31372 0 0.00 0 21 1 0 0
v_all_wares 42772 42772 0 0.00 0 131 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 2476 9 0 0
v_cancel_client_order 3822 3822 0 0.00 0 6442 9 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 4742 9 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 2231 5 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 178 3 0 0
v_cancel_supplier_invoice 840 826 14 1.66 0 3959 21 0 14
v_cancel_supplier_order 898 898 0 0.00 0 241 3 0 0
v_cancel_write_off 2154 2154 0 0.00 0 2779 15 0 0
v_max_id_clo_ord 5681 5681 0 0.00 0 594 1 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 2152 21 0 0
v_max_id_ord_sup 5653 5653 0 0.00 0 4692 4 0 0
v_max_non_paid_invoice 1799 1799 0 0.00 0 2185 5 0 0
v_max_non_paid_realizn 3885 3885 0 0.00 0 1535 4 0 0
v_min_id_clo_ord 5681 5681 0 0.00 0 3705 6 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 3291 11 0 0
v_min_id_ord_sup 5653 5653 0 0.00 0 3299 5 0 0
v_min_non_paid_invoice 6151 6151 0 0.00 0 4913 15 0 0
v_min_non_paid_realizn 7309 7309 0 0.00 0 7096 20 0 0
v_our_firm 10682 10682 0 0.00 0 15 1 0 0
v_reserve_write_off 21008 21008 0 0.00 0 6631 7 0 0
x_lock_depdocs_on_canc_invoice 11217 11217 0 0.00 0 24431 114 0 0
x_lock_depdocs_on_canc_sup_ord 449 449 0 0.00 0 4464 288 0 0
x_make_qstorno_1000_1200 5681 5681 0 0.00 1 33724 1238 0 0
x_make_qstorno_1200_2000 5653 5653 0 0.00 3 74454 3057 0 0
x_make_qstorno_2100_3300 477070 477070 0 0.00 0 30200 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-01-2023 19:39:58 to 07-01-2023 19:40:02.

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
17:08:35 9 8192000000 8811135632 8826695680 15535184 0.189 4 4 5 0 15761904 87456 139264
17:09:36 26003 8192000000 9909183104 10220769280 987360896 10.756 100 81 162 0 1015126672 6510912 21254864
17:11:08 14138 8192000000 9963017152 10289512448 1041954320 11.283 100 91 183 0 1074809584 9421520 23433744
17:12:34 15979 8192000000 9997037648 10332872704 1077320672 11.622 100 92 184 0 1110742848 10826720 22595456
17:13:54 7895 8192000000 10031985920 10377900032 1112753600 11.958 100 81 162 0 1148033664 16135376 19144688
17:15:04 11309 8192000000 10073196672 10431901696 1161802176 12.420 100 76 152 1 1190830368 11917680 17110512
17:16:18 4899 8192000000 10111089696 10479964160 1200824032 12.784 100 73 143 3 1228946704 11910112 16212560
17:17:27 2773 8192000000 10145156000 10518634496 1233777200 13.089 100 86 170 2 1266760368 13349264 19633904
17:18:31 1742 8192000000 10173960464 10550317056 1261598768 13.345 100 77 156 0 1290765648 12308304 16858576
17:19:35 1675 8192000000 10203557104 10584301568 1287134384 13.578 100 84 168 2 1320434320 14305648 18994288
17:20:39 1053 8192000000 10225423904 10611269632 1306579632 13.755 100 86 171 0 1342550160 17425696 18544832
17:21:41 1278 8192000000 10207545296 10610675712 1309241664 13.779 100 55 110 0 1329605696 7624960 12739072
17:22:44 1838 8192000000 10216081328 10622808064 1317484240 13.854 100 44 87 1 1335311024 7826688 10000096
17:23:47 18580 8192000000 10200118576 10624856064 1322220320 13.897 100 11 20 0 1329983136 5692976 2069840
17:25:06 3840 8192000000 10228524816 10638462976 1334071600 14.004 100 49 98 2 1351808096 5253136 12483360
17:26:13 31605 8192000000 10232121008 10654228480 1334688768 14.009 100 28 57 0 1350799120 10372000 5738352
17:27:45 8101 8192000000 10213211184 10629959680 1300227056 13.697 98 31 62 1 1314572976 7450272 6895648
17:28:54 6191 8192000000 10180260416 10586681344 1275646640 13.473 98 18 36 0 1284996512 5153696 4196176
17:30:00 12108 8192000000 10173621664 10588815360 1273994640 13.458 99 10 20 0 1281557728 5346864 2216224
17:31:17 44489 8192000000 10125762032 10522664960 1159249808 12.396 91 36 70 1 1172991216 5129168 8612240
17:33:02 7466 8192000000 10151769200 10553683968 1227728592 13.033 97 30 60 0 1240607360 5653280 7225488
17:34:11 5020 8192000000 10165895808 10569117696 1268807216 13.411 100 40 80 0 1284422160 5688896 9926048
17:35:19 15043 8192000000 10184812416 10598633472 1293688480 13.638 100 17 34 0 1303292464 5499536 4104448
17:36:34 18789 8192000000 10200790880 10601156608 1297565680 13.673 100 28 50 3 1307741792 4310064 5866048
17:37:55 7530 8192000000 10208774960 10623668224 1314464256 13.827 100 32 62 1 1328344352 7144304 6735792
17:39:09 20901 8192000000 10242241152 10658222080 1340116224 14.058 100 43 83 0 1356189200 6897872 9175104
17:40:33 35080 8192000000 10277841920 10693271552 1366421088 14.295 100 42 83 0 1387511008 11912448 9177472
17:42:12 53871 8192000000 10277897120 10713247744 1378830256 14.406 100 32 64 0 1398332080 11709616 7792208
17:44:09 6720 8192000000 10304303776 10726842368 1389652256 14.503 100 40 76 0 1412730000 14220400 8857344
17:45:19 5637 8192000000 10318196592 10740109312 1388415056 14.492 99 50 95 1 1416293840 16794944 11083840
17:46:30 12038 8192000000 10324964848 10746355712 1399304352 14.589 100 57 114 0 1433500320 20194144 14001824
17:47:49 4374 8192000000 10343872224 10765434880 1406094432 14.649 100 64 125 2 1450586000 29045968 15445600
17:49:00 21820 8192000000 10311054128 10740023296 1371778528 14.343 97 40 79 0 1402510336 21549056 9182752
17:50:25 4881 8192000000 10348204640 10768916480 1401684944 14.610 99 66 131 2 1446116096 28856432 15574720
17:51:37 2952 8192000000 10354349440 10774941696 1416546240 14.742 100 78 155 2 1460667296 25169968 18951088
17:52:45 6693 8192000000 10347601008 10771767296 1400826496 14.602 99 66 131 0 1444449520 27744048 15878976
17:54:00 6395 8192000000 10350811056 10770219008 1402141312 14.614 100 68 134 1 1454812208 36121760 16549136
17:55:13 4124 8192000000 10344279872 10754883584 1387047744 14.480 99 76 148 0 1436165424 30244864 18872816
17:56:22 20829 8192000000 10312637776 10737078272 1377089744 14.391 100 36 72 0 1418070016 30937232 10043040
17:57:45 1493 8192000000 10340912880 10749177856 1381424080 14.429 100 74 149 1 1438109456 37308976 19376400
17:58:48 6644 8192000000 10299962464 10717487104 1360610368 14.243 100 36 71 0 1401528752 31562832 9355552
18:00:00 8710 8192000000 10306179376 10730201088 1366104544 14.292 100 36 70 0 1408460048 32471520 9883984
18:01:15 22722 8192000000 10295924640 10722402304 1357554080 14.215 99 32 64 0 1393686256 28104912 8027264
18:02:41 33365 8192000000 10288428352 10726207488 1358531856 14.224 99 19 36 3 1391378688 27679072 5167760
18:04:19 50642 8192000000 10291036944 10718138368 1339461584 14.053 99 17 34 0 1369502928 25907408 4133936
18:06:10 18762 8192000000 10259559088 10696450048 1350015920 14.148 100 42 84 0 1366688192 6110896 10561376
18:07:33 42899 8192000000 10261613152 10693394432 1349511952 14.143 99 30 61 0 1365065904 9495248 6058704
18:09:29 62965 8192000000 10286201584 10734178304 1360310032 14.240 99 22 39 0 1376592624 12278896 4003696
18:11:39 104587 8192000000 10279109184 10718851072 1364274688 14.276 99 20 39 0 1378782112 10319296 4188128
18:14:29 50417 8192000000 10287559232 10746167296 1375573232 14.377 100 23 46 1 1398302800 17199840 5529728
18:16:27 66906 8192000000 10285479056 10739634176 1370191808 14.329 99 23 46 0 1391949616 16349936 5407872
18:18:36 35600 8192000000 10296599360 10745393152 1382712688 14.441 100 53 103 0 1407578416 12475568 12390160
18:20:17 55219 8192000000 10293047712 10748944384 1393705488 14.539 100 10 20 0 1403409152 7397104 2306560
18:22:15 49883 8192000000 10297572240 10764087296 1400436064 14.599 100 15 31 0 1413756656 9636176 3684416
18:24:13 42131 8192000000 10310560400 10762993664 1390810960 14.513 99 18 36 0 1412384816 16983248 4590608
18:26:54 62610 8192000000 10303524144 10761105408 1381794128 14.433 99 21 42 0 1404436976 17236192 5406656
18:28:57 51354 8192000000 10282620464 10746048512 1366364672 14.294 98 7 12 0 1374967664 7216992 1386000
18:30:54 23293 8192000000 10294200928 10748141568 1387967296 14.488 100 25 51 0 1402760640 9220336 5573008
18:32:17 55669 8192000000 10307849536 10757861376 1371999760 14.345 98 17 34 0 1390313904 14518208 3795936
18:34:16 45307 8192000000 10271928032 10717237248 1311700176 13.801 94 25 50 0 1328615056 11398304 5516576
18:36:01 26730 8192000000 10260225168 10699739136 1314367520 13.826 96 31 62 0 1334794960 13376688 7050752
18:37:30 65234 8192000000 10276718160 10718273536 1340541104 14.062 98 24 48 0 1361535936 15807056 5187776
18:39:42 71583 8192000000 10275874528 10731347968 1337877296 14.038 98 25 50 0 1359450848 16293616 5279936
18:41:56 85193 8192000000 10287526608 10736676864 1368200032 14.311 100 19 36 1 1393592960 21945280 3447648
18:44:31 13743 8192000000 10283600112 10726514688 1367222192 14.302 100 37 75 0 1388731920 12700112 8809616
18:48:19 72437 8192000000 10258486512 10727731200 1340584064 14.063 97 14 28 0 1360474384 16540304 3350016
18:50:31 32890 8192000000 10275022432 10713919488 1362138368 14.257 100 51 102 1 1383314832 8265344 12911120
18:52:04 73113 8192000000 10295727504 10758156288 1382881328 14.442 100 20 40 0 1404875808 17430704 4563776
18:54:17 49862 8192000000 10272176128 10729218048 1334091248 14.004 97 22 44 0 1354967696 15176624 5699824
18:56:08 45400 8192000000 10284848960 10731184128 1360862480 14.245 99 20 40 1 1375848560 10878080 4108000
18:57:57 120520 8192000000 10287547616 10740473856 1351956768 14.165 98 10 20 0 1374422256 20502816 1962672
19:01:26 69907 8192000000 10288200576 10733850624 1313919120 13.822 95 17 34 1 1333334784 15598784 3816880
19:03:39 109387 8192000000 10282618784 10731069440 1349951792 14.147 99 30 60 1 1365395216 8608448 6834976
19:06:29 15380 8192000000 10295734048 10748211200 1384417344 14.456 100 25 49 3 1401682352 11700400 5564608
19:07:45 114164 8192000000 10305082192 10771324928 1396901728 14.567 100 12 24 0 1418740560 19272064 2566768
19:10:40 58057 8192000000 10295191632 10768011264 1401100768 14.605 100 9 18 0 1416106416 13171968 1833680
19:12:38 80207 8192000000 10307530464 10770702336 1373105504 14.355 98 13 25 0 1382689936 6939696 2644736
19:15:01 65069 8192000000 10291315296 10753564672 1359551312 14.233 97 19 38 0 1376920144 12648704 4720128
19:17:09 70978 8192000000 10302675024 10768236544 1399965952 14.595 100 18 36 0 1414474304 10577632 3930720
19:19:21 66133 8192000000 10274858496 10729164800 1318170704 13.860 95 6 10 0 1328650192 9583312 896176
19:21:30 50590 8192000000 10284292096 10725019648 1314744448 13.829 95 38 74 0 1332259488 9515600 7999440
19:23:20 86501 8192000000 10254019904 10696028160 1281419792 13.526 94 27 54 0 1301517424 14073072 6024560
19:25:49 91211 8192000000 10263828320 10699554816 1308391936 13.771 96 39 78 1 1330586448 13610896 8583616
19:28:21 50982 8192000000 10270599424 10717347840 1360510624 14.242 100 19 38 0 1373052272 8355152 4186496
19:30:12 56115 8192000000 10260750800 10730217472 1346938160 14.120 98 18 35 0 1358114320 7764160 3412000
19:32:18 65234 8192000000 10280434192 10714009600 1340011776 14.058 98 8 17 0 1353018816 11140016 1867024
19:34:24 80594 8192000000 10249780240 10715897856 1325415024 13.926 97 16 32 0 1340518064 11231840 3871200
19:36:46 76024 8192000000 10234074464 10694770688 1297853088 13.676 96 15 31 0 1311892848 10526864 3512896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-01-2023 19:40:04 to 07-01-2023 19:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 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 07-01-2023 19:40:20 to 07-01-2023 19:40:22.

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          243246
MON$OLDEST_ACTIVE               244211
MON$OLDEST_SNAPSHOT             244211
MON$NEXT_TRANSACTION            244211
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                       2025760
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.33652 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33652 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33652 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 ---
:63: 
:64: mscs-test-161.budzdorov.ru    Sat Jan  7 19:38:33 2023
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Sat Jan  7 19:38:33 2023
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Sat Jan  7 19:38:38 2023
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:74: 
:75: 
:76: mscs-test-161.budzdorov.ru    Sat Jan  7 19:38:43 2023
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:78: 
--- end of diff output ---

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

07.01.23 19:40:23. 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
2023-01-07 19:40:26 No severe PSQL-related problems occured
07.01.23 19:40:27. End of report.