Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.03.2023 16: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 15139356 kB
MemAvailable 30405300 kB
Buffers 0 kB
Cached 16544228 kB
SwapCached 3028 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-03-04 16:38:40
DTS_END                         2023-03-04 16:38:40.3040
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/34048
TRN_ID                          242395
ATT_ID                          649
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: 9484.14 20648 1138097
customer order: creation 172.32 373 20678
customer order: refuse 14.79 349 1775
order to supplier: creation 45.68 1209 5482
order to supplier: removal 3.47 99763 416
invoice (draft): creation 45.98 3099 5517
invoice (draft): removal 54.78 695 6573
invoice accept: apply 42.18 17066 5061
invoice accept: cancel 50.02 805 6002
customer reserve: creation 3885.76 169 466291
customer reserve: removal 4862.48 56 583498
realization accept: apply 84.00 178 10080
realization accept: cancel 93.92 102 11270
payment from customer: creation 58.80 122 7056
payment from customer: removal 5.85 91 702
payment to supplier: creation 48.96 135 5875
payment to supplier: removal 3.53 97 423
service: total inventory turnovers 4.50 1241 540
service: total monetary turnovers 6.32 52 758
service: aggregate perf. data 0.80 22343 96
service: refresh index statistics 0.03 265023 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-03-2023 16:39:22 to 04-03-2023 16:39:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2081
TEST_TIME 2 2837
TEST_TIME 3 3512
TEST_TIME 4 3557
TEST_TIME 5 4348
TEST_TIME 6 4744
TEST_TIME 7 5076
TEST_TIME 8 5303
TEST_TIME 9 5484
TEST_TIME 10 5730
TEST_TIME 11 5808
TEST_TIME 12 6185
TEST_TIME 13 6060
TEST_TIME 14 6160
TEST_TIME 15 6486
TEST_TIME 16 6440
TEST_TIME 17 6556
TEST_TIME 18 6904
TEST_TIME 19 7102
TEST_TIME 20 7469
TEST_TIME 21 7819
TEST_TIME 22 8247
TEST_TIME 23 8684
TEST_TIME 24 8665
TEST_TIME 25 8665
TEST_TIME 26 8745
TEST_TIME 27 9176
TEST_TIME 28 9062
TEST_TIME 29 9908
TEST_TIME 30 10496
TEST_TIME 31 10624
TEST_TIME 32 10618
TEST_TIME 33 10706
TEST_TIME 34 10647
TEST_TIME 35 10367
TEST_TIME 36 10191
TEST_TIME 37 10120
TEST_TIME 38 10068
TEST_TIME 39 9888
TEST_TIME 40 9722
TEST_TIME 41 9593
TEST_TIME 42 9797
TEST_TIME 43 9798
TEST_TIME 44 9726
TEST_TIME 45 9647
TEST_TIME 46 9511
TEST_TIME 47 9452
TEST_TIME 48 9536
TEST_TIME 49 9426
TEST_TIME 50 9352
TEST_TIME 51 9279
TEST_TIME 52 9227
TEST_TIME 53 9153
TEST_TIME 54 9137
TEST_TIME 55 9125
TEST_TIME 56 9030
TEST_TIME 57 9112
TEST_TIME 58 9160
TEST_TIME 59 9095
TEST_TIME 60 9048
TEST_TIME 61 9231
TEST_TIME 62 9234
TEST_TIME 63 9247
TEST_TIME 64 9273
TEST_TIME 65 9278
TEST_TIME 66 9280
TEST_TIME 67 9426
TEST_TIME 68 9482
TEST_TIME 69 9492
TEST_TIME 70 9575
TEST_TIME 71 9660
TEST_TIME 72 9532
TEST_TIME 73 9457
TEST_TIME 74 9466
TEST_TIME 75 9445
TEST_TIME 76 9391
TEST_TIME 77 9343
TEST_TIME 78 9350
TEST_TIME 79 9330
TEST_TIME 80 9253
TEST_TIME 81 9231
TEST_TIME 82 9294
TEST_TIME 83 9314
TEST_TIME 84 9256
TEST_TIME 85 9324
TEST_TIME 86 9337
TEST_TIME 87 9332
TEST_TIME 88 9322
TEST_TIME 89 9296
TEST_TIME 90 9372
TEST_TIME 91 9457
TEST_TIME 92 9482
TEST_TIME 93 9517
TEST_TIME 94 9520
TEST_TIME 95 9534
TEST_TIME 96 9550
TEST_TIME 97 9519
TEST_TIME 98 9485
TEST_TIME 99 9431
TEST_TIME 100 9434
TEST_TIME 101 9418
TEST_TIME 102 9391
TEST_TIME 103 9369
TEST_TIME 104 9364
TEST_TIME 105 9391
TEST_TIME 106 9359
TEST_TIME 107 9338
TEST_TIME 108 9349
TEST_TIME 109 9321
TEST_TIME 110 9356
TEST_TIME 111 9357
TEST_TIME 112 9344
TEST_TIME 113 9403
TEST_TIME 114 9418
TEST_TIME 115 9401
TEST_TIME 116 9412
TEST_TIME 117 9379
TEST_TIME 118 9470
TEST_TIME 119 9492
TEST_TIME 120 9486

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-03-2023 16:39:25 to 04-03-2023 16:39:29.

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 1571522 1571522 0 0.00 0 31731 6 0 0
doc_list_biud 1571522 1571522 0 0.00 0 45368 26 0 0
sp_add_invoice_to_stock 5155 5061 94 1.82 46 177071 17066 0 94
sp_cancel_adding_invoice 6002 6002 0 0.00 2 74818 805 0 0
sp_cancel_client_order 1775 1775 0 0.00 4 14422 350 0 0
sp_cancel_customer_reserve 583498 583498 0 0.00 0 34576 56 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 7257 92 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 2 9796 98 0 0
sp_cancel_supplier_invoice 6582 6573 9 0.13 1 17989 696 0 9
sp_cancel_supplier_order 416 416 0 0.00 6 986952 99763 0 0
sp_cancel_write_off 11270 11270 0 0.00 1 10321 102 0 0
sp_client_order 20678 20678 0 0.00 3 58502 373 0 0
sp_customer_reserve 466291 466291 0 0.00 0 56061 170 0 0
sp_fill_shopping_cart 31675 31675 0 0.00 1 12869 41 0 0
sp_fill_shopping_cart_clo_res 466291 466291 0 0.00 0 21721 13 0 0
sp_get_clo_for_invoice 5058 5058 0 0.00 0 13288 305 0 0
sp_kill_cost_storno 18397 18397 0 0.00 0 28225 59 0 0
sp_kill_qstorno_ret_qs2qd 1185255 1185255 0 0.00 0 31349 30 0 0
sp_kill_qty_storno 624672 624672 0 0.00 0 45365 65 0 0
sp_lock_selected_doc 20988 20988 0 0.00 0 7852 7 0 0
sp_make_cost_storno 28069 28069 0 0.00 0 21963 54 0 0
sp_multiply_rows_for_pdistr 28069 28069 0 0.00 0 21283 32 0 0
sp_multiply_rows_for_qdistr 20678 20678 0 0.00 0 58494 168 0 0
sp_pay_from_customer 7056 7056 0 0.00 4 31735 122 0 0
sp_pay_to_supplier 5875 5875 0 0.00 4 28956 135 0 0
sp_payment_common 12931 12931 0 0.00 1 28934 44 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 14415 316 0 0
sp_qd_handle_on_invoice_upd_sts 11060 11060 0 0.00 0 45361 315 0 0
sp_qd_handle_on_reserve_upd_sts 10080 10080 0 0.00 0 8318 47 0 0
sp_reserve_write_off 10080 10080 0 0.00 6 29114 179 0 0
sp_supplier_invoice 5517 5517 0 0.00 5 92808 3099 0 0
sp_supplier_order 5482 5482 0 0.00 4 63157 1209 0 0
srv_aggregate_perf_data 96 96 0 0.00 2372 531573 22344 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1272 130949 45294 0 0
srv_find_qd_qs_mism 1091070 1091070 0 0.00 0 15650 5 0 0
srv_make_invnt_saldo 540 540 0 0.00 39 100534 1241 0 0
srv_make_money_saldo 758 758 0 0.00 1 15429 52 0 0
srv_recalc_idx_stat 4 4 0 0.00 182408 386271 265024 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5062 5062 0 0.00 0 16745 171 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 0 4744 100 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 2857 10 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 741 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 822 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 4892 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 3362 24 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 27951 1563 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 4139 11 0 0
t$perf-norm:sp_client_order 20678 20678 0 0.00 0 7026 14 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 467 3 0 0
t$perf-norm:sp_pay_from_customer 7056 7056 0 0.00 0 8014 13 0 0
t$perf-norm:sp_pay_to_supplier 5875 5875 0 0.00 0 8147 11 0 0
t$perf-norm:sp_reserve_write_off 10080 10080 0 0.00 0 4239 10 0 0
t$perf-norm:sp_supplier_invoice 5517 5517 0 0.00 0 8012 16 0 0
t$perf-norm:sp_supplier_order 5482 5482 0 0.00 0 4822 15 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 300 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 761 15 0 0
t$perf-norm:srv_make_invnt_saldo 540 540 0 0.00 0 3382 26 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 1017 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 58 18 0 0
v_add_invoice_to_stock 10304 10116 188 1.82 0 5495 17 0 188
v_all_customers 37416 37416 0 0.00 0 19 1 0 0
v_all_suppliers 30508 30508 0 0.00 0 23 1 0 0
v_all_wares 41352 41352 0 0.00 0 62 1 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 2624 10 0 0
v_cancel_client_order 3550 3550 0 0.00 0 2602 6 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 298 3 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 4051 8 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 582 5 0 0
v_cancel_supplier_invoice 838 820 18 2.14 0 3431 20 0 18
v_cancel_supplier_order 832 832 0 0.00 0 2537 8 0 0
v_cancel_write_off 1960 1960 0 0.00 0 2717 15 0 0
v_max_id_clo_ord 5482 5482 0 0.00 0 2249 2 0 0
v_max_id_clo_res 987 987 0 0.00 0 4784 17 0 0
v_max_id_ord_sup 5517 5517 0 0.00 0 4480 4 0 0
v_max_non_paid_invoice 1620 1620 0 0.00 0 1701 7 0 0
v_max_non_paid_realizn 3757 3757 0 0.00 0 3199 8 0 0
v_min_id_clo_ord 5482 5482 0 0.00 0 8884 14 0 0
v_min_id_clo_res 987 987 0 0.00 0 5424 18 0 0
v_min_id_ord_sup 5517 5517 0 0.00 0 7010 11 0 0
v_min_non_paid_invoice 5875 5875 0 0.00 0 28921 39 0 0
v_min_non_paid_realizn 7056 7056 0 0.00 0 27493 32 0 0
v_our_firm 10534 10534 0 0.00 0 13 1 0 0
v_reserve_write_off 20160 20160 0 0.00 0 6980 5 0 0
x_lock_depdocs_on_canc_invoice 12165 12165 0 0.00 0 12794 121 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 6651 356 0 0
x_make_qstorno_1000_1200 5482 5482 0 0.00 1 63151 1127 0 0
x_make_qstorno_1200_2000 5517 5517 0 0.00 2 89434 2951 0 0
x_make_qstorno_2100_3300 466291 466291 0 0.00 0 56060 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-03-2023 16:39:30 to 04-03-2023 16:39:34.

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
14:08:44 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:44 20859 8192000000 9906275984 10217881600 987177552 10.754 100 87 174 0 1015676896 5595776 22903568
14:11:07 16184 8192000000 9944034432 10264678400 1028138624 11.151 100 90 180 0 1059248112 7702912 23406576
14:12:34 23613 8192000000 9984937680 10317811712 1066462880 11.518 100 85 170 0 1097676736 9563008 21650848
14:14:12 10495 8192000000 10030872048 10376667136 1116884928 11.998 100 72 144 0 1144440240 9493728 18061584
14:15:26 9710 8192000000 10081115424 10439168000 1165286176 12.453 100 75 148 0 1193052640 10921856 16844608
14:16:39 4437 8192000000 10114775520 10481618944 1207722544 12.848 100 77 154 0 1237921456 12881824 17317088
14:17:46 2573 8192000000 10152127216 10524962816 1236790688 13.117 100 85 170 0 1269647456 14268048 18588720
14:18:51 2284 8192000000 10176630736 10555744256 1263300720 13.360 100 86 170 1 1296563504 14155392 19107392
14:19:54 4099 8192000000 10194724512 10586951680 1290214016 13.606 100 76 150 0 1320531136 14192320 16124800
14:21:05 24766 8192000000 10193932496 10604179456 1299401808 13.690 100 33 64 0 1314107760 7154864 7551088
14:22:34 7716 8192000000 10205834112 10615607296 1314262320 13.825 100 19 39 0 1324254768 5884768 4107680
14:23:45 6606 8192000000 10213182192 10630328320 1327923552 13.948 100 22 44 0 1338808576 6155536 4729488
14:24:54 26943 8192000000 10221544432 10646097920 1338914928 14.048 100 23 45 1 1349418240 5428048 5075264
14:26:43 2401 8192000000 10251840976 10661740544 1337809008 14.038 100 39 78 0 1359052880 12464384 8779488
14:27:49 4800 8192000000 10229430304 10644611072 1332722000 13.992 100 33 66 2 1345877328 5617968 7537360
14:28:54 15295 8192000000 10204962976 10625409024 1256759008 13.300 94 11 22 0 1264263472 5019568 2484896
14:30:13 26930 8192000000 10170370640 10586148864 1211420464 12.882 92 25 46 0 1222150208 5369248 5360496
14:31:40 5800 8192000000 10126768352 10522509312 1182483680 12.613 95 35 72 1 1196266320 6195984 7586656
14:32:46 6212 8192000000 10145148144 10540720128 1226982944 13.026 98 22 42 0 1236970976 5208496 4779536
14:33:53 8236 8192000000 10164075280 10561691648 1255460448 13.288 99 28 57 0 1267733504 5381840 6891216
14:35:02 27990 8192000000 10169596880 10582147072 1282647248 13.537 100 40 80 0 1297954880 5674144 9633488
14:36:34 8797 8192000000 10190471728 10603954176 1301085792 13.705 100 20 39 1 1310509536 5044192 4379552
14:37:45 4600 8192000000 10208597952 10618658816 1308080816 13.769 100 26 52 0 1324029808 9550784 6398208
14:38:53 25007 8192000000 10229427744 10641629184 1332231328 13.987 100 37 72 1 1346176448 5563264 8381856
14:40:24 19112 8192000000 10253149840 10670542848 1349666752 14.144 100 33 63 2 1365782496 8538880 7576864
14:41:56 7602 8192000000 10265950528 10686414848 1360078304 14.238 100 45 90 2 1381025760 10120464 10826992
14:43:07 36534 8192000000 10273583456 10704879616 1369044352 14.318 100 37 74 0 1394705360 17212128 8448880
14:44:51 11153 8192000000 10290928416 10712813568 1374485712 14.367 100 54 107 1 1403889088 16631328 12772048
14:46:06 6942 8192000000 10305909360 10733268992 1388896080 14.496 100 47 95 2 1419958128 20383712 10678336
14:47:26 14702 8192000000 10327039616 10753765376 1402707600 14.619 100 43 84 0 1438966352 25842112 10416640
14:48:49 10277 8192000000 10331587744 10754605056 1382810864 14.442 98 55 108 2 1424009680 28229312 12969504
14:50:06 4972 8192000000 10352125584 10774867968 1411866064 14.701 100 62 123 0 1458373248 31230912 15276272
14:51:19 15670 8192000000 10334647520 10761830400 1399752400 14.593 99 48 92 0 1437662880 26173728 11736752
14:53:01 8083 8192000000 10322802768 10742276096 1361305328 14.249 96 46 91 0 1405170720 32391856 11473536
14:54:20 9778 8192000000 10317072336 10737901568 1369831488 14.326 98 43 86 0 1413452352 32817232 10803632
14:55:34 2948 8192000000 10342353056 10761146368 1396047872 14.560 100 63 127 1 1453587488 41815056 15724560
14:56:43 3310 8192000000 10358984480 10768519168 1391129648 14.516 100 83 161 1 1460931232 50474464 19327120
14:57:51 1270 8192000000 10365426528 10770870272 1390369136 14.509 100 77 153 1 1469140896 59534784 19236976
14:58:55 5049 8192000000 10342212624 10759675904 1380048480 14.417 100 53 107 0 1448778688 54785696 13944512
15:00:10 2859 8192000000 10330069760 10728886272 1360866544 14.245 99 81 159 1 1431369360 50559376 19943440
15:01:19 2088 8192000000 10352185200 10771357696 1388750576 14.495 100 65 127 2 1456700752 51219840 16730336
15:02:23 20068 8192000000 10322540608 10752417792 1367113808 14.301 99 36 72 0 1422412640 45076832 10222000
15:03:56 5824 8192000000 10329466976 10760486912 1380464224 14.421 100 45 90 0 1439558128 47275248 11818656
15:05:17 33667 8192000000 10307036688 10745122816 1359411984 14.232 99 24 48 0 1408645984 42051104 7182896
15:06:52 29854 8192000000 10307809664 10748428288 1352497056 14.170 100 23 46 0 1412022352 52554816 6970480
15:08:24 46265 8192000000 10283885072 10731667456 1342861776 14.083 98 10 20 0 1369260592 24213328 2185488
15:10:12 19833 8192000000 10284931760 10718810112 1361408864 14.250 99 33 65 0 1379008320 10559392 7040064
15:11:32 100955 8192000000 10269055200 10717519872 1330598640 13.973 97 7 14 0 1344553888 12669328 1285920
15:15:18 53097 8192000000 10283926416 10731757568 1374547728 14.368 100 30 60 0 1391879632 10308160 7023744
15:17:16 100362 8192000000 10293704704 10745352192 1370224896 14.329 99 20 39 0 1385339600 10796384 4318320
15:19:57 85547 8192000000 10286929024 10746335232 1387682224 14.485 100 10 20 0 1408093824 17796288 2615312
15:22:25 50834 8192000000 10284206640 10732523520 1371518432 14.341 99 8 16 0 1380440512 7091552 1830528
15:24:19 99485 8192000000 10292103632 10753380352 1384668112 14.458 100 18 36 0 1399628560 10793744 4166704
15:26:59 76980 8192000000 10300535808 10760118272 1392885168 14.532 100 16 32 0 1408599024 12254880 3458976
15:29:17 7304 8192000000 10307934576 10764255232 1401574592 14.609 100 49 95 0 1425992048 13440800 10976656
15:30:27 53732 8192000000 10304828464 10768105472 1395930192 14.559 99 10 20 0 1409698144 11540704 2227248
15:32:21 54148 8192000000 10292281120 10756763648 1374596384 14.368 98 32 64 0 1394715600 12607232 7511984
15:35:18 92753 8192000000 10303623776 10778959872 1375895328 14.380 98 5 10 0 1396762624 19428688 1438608
15:37:52 70481 8192000000 10312668048 10774024192 1392610944 14.529 99 16 32 0 1413728528 17351312 3766272
15:40:03 28666 8192000000 10288687584 10741407744 1352593936 14.171 97 23 46 0 1364758592 7342064 4822592
15:41:37 30462 8192000000 10309104640 10753757184 1379379872 14.411 99 34 68 0 1394890784 8226272 7284640
15:43:10 18312 8192000000 10290890288 10734985216 1356504416 14.206 98 43 81 0 1376075440 8656944 10914080
15:44:29 73976 8192000000 10248693360 10712227840 1252196496 13.258 91 21 42 1 1276607968 19107904 5303568
15:46:44 130949 8192000000 10276403456 10730602496 1250954032 13.247 91 14 28 0 1271134352 17007104 3173216
15:50:32 126755 8192000000 10233578064 10686480384 1290360384 13.608 95 15 30 1 1303002976 9471120 3171472
15:54:25 55827 8192000000 10253316656 10708209664 1353064160 14.175 100 15 29 0 1372452096 16329840 3058096
15:56:53 85284 8192000000 10249064080 10698006528 1332642656 13.991 98 13 24 0 1349434896 13935248 2856992
15:59:18 86388 8192000000 10249093856 10706395136 1331188000 13.978 98 25 48 0 1353724112 16791408 5744704
16:01:52 79738 8192000000 10244924128 10695168000 1294581568 13.646 96 12 24 0 1308395152 11010032 2803552
16:04:32 42235 8192000000 10261488448 10704814080 1342995648 14.084 99 44 88 0 1363647664 10838384 9813632
16:06:21 27914 8192000000 10262420848 10705522688 1339817056 14.056 99 31 62 1 1356398368 10015296 6566016
16:07:49 44760 8192000000 10282050208 10727362560 1356057552 14.202 99 25 50 0 1377757472 16194160 5505760
16:09:34 75214 8192000000 10279092640 10728615936 1346157728 14.113 98 14 28 0 1361508384 12697120 2653536
16:12:24 75846 8192000000 10310518592 10754158592 1390870416 14.514 100 19 38 0 1405645568 10952000 3823152
16:14:41 81272 8192000000 10285001808 10726817792 1363885552 14.272 98 23 46 1 1377580976 8767632 4927792
16:17:24 63907 8192000000 10292960960 10747383808 1374086480 14.364 99 31 62 0 1391869312 11127232 6655600
16:19:39 43328 8192000000 10300943728 10754371584 1378648336 14.404 99 34 68 0 1398739264 12213536 7877392
16:21:23 44601 8192000000 10296789024 10758615040 1385302160 14.464 99 11 22 1 1400699776 12872464 2525152
16:23:12 63832 8192000000 10297355104 10762686464 1386529648 14.475 99 20 40 0 1401681696 10289024 4863024
16:25:22 66419 8192000000 10275022800 10751033344 1355003856 14.192 97 14 28 0 1369607136 11285072 3318208
16:27:29 20316 8192000000 10277189808 10733416448 1378889792 14.407 100 34 68 1 1392707744 5606304 8211648
16:28:50 75657 8192000000 10276847072 10732584960 1337026880 14.031 97 10 20 0 1353321824 13963936 2331008
16:31:10 67433 8192000000 10273595200 10722189312 1333925168 14.003 97 18 36 0 1347269440 9561856 3782416
16:33:18 75408 8192000000 10275368176 10721374208 1302980368 13.722 95 14 28 0 1318341536 12384880 2976288
16:35:33 28646 8192000000 10262851392 10688573440 1325484448 13.926 98 54 106 0 1353737600 16062320 12190832
16:37:05 51613 8192000000 10266745312 10705985536 1309086016 13.778 97 23 45 0 1328838000 14792496 4959488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-03-2023 16:39:36 to 04-03-2023 16:39:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-03-2023 16:39:52 to 04-03-2023 16:39:54.

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          241163
MON$OLDEST_ACTIVE               242526
MON$OLDEST_SNAPSHOT             242526
MON$NEXT_TRANSACTION            242526
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                       2043488
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:950: 
:951: mscs-test-161.budzdorov.ru    Sat Mar  4 16:38:49 2023
:952:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:953: 
:954: 
:955: mscs-test-161.budzdorov.ru    Sat Mar  4 16:38:49 2023
:956:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:957: 
:958: 
:959: mscs-test-161.budzdorov.ru    Sat Mar  4 16:38:49 2023
:960:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:961: 
:962: 
:963: mscs-test-161.budzdorov.ru    Sat Mar  4 16:38:51 2023
:964:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:965: 
:966: 
:967: mscs-test-161.budzdorov.ru    Sat Mar  4 16:38:53 2023
:968:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:969: 
--- end of diff output ---

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

04.03.23 16:39:55. 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-03-04 16:39:58 No severe PSQL-related problems occured
04.03.23 16:39:59. End of report.