Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.2022 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 15413168 kB
MemAvailable 30501768 kB
Buffers 0 kB
Cached 16305216 kB
SwapCached 2160 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-10-15 16:38:32
DTS_END                         2022-10-15 16:38:32.5820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56352
TRN_ID                          239060
ATT_ID                          707
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: 9151.17 23929 1098140
customer order: creation 167.38 361 20085
customer order: refuse 14.68 320 1761
order to supplier: creation 44.05 1280 5286
order to supplier: removal 3.49 101962 419
invoice (draft): creation 44.77 3373 5372
invoice (draft): removal 53.47 768 6416
invoice accept: apply 40.59 17783 4871
invoice accept: cancel 48.42 775 5810
customer reserve: creation 3741.79 177 449015
customer reserve: removal 4705.28 59 564634
realization accept: apply 81.34 199 9761
realization accept: cancel 80.77 109 9692
payment from customer: creation 57.56 147 6907
payment from customer: removal 5.55 78 666
payment to supplier: creation 46.99 165 5639
payment to supplier: removal 3.60 50 432
service: total inventory turnovers 4.53 1296 544
service: total monetary turnovers 6.16 36 739
service: aggregate perf. data 0.73 30173 87
service: refresh index statistics 0.03 319466 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-10-2022 16:39:52 to 15-10-2022 16:39:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6022
TEST_TIME 2 5670
TEST_TIME 3 6943
TEST_TIME 4 5942
TEST_TIME 5 6254
TEST_TIME 6 6040
TEST_TIME 7 5801
TEST_TIME 8 5583
TEST_TIME 9 5345
TEST_TIME 10 5656
TEST_TIME 11 5495
TEST_TIME 12 5484
TEST_TIME 13 5395
TEST_TIME 14 5270
TEST_TIME 15 5628
TEST_TIME 16 5615
TEST_TIME 17 5870
TEST_TIME 18 6006
TEST_TIME 19 6148
TEST_TIME 20 6422
TEST_TIME 21 6593
TEST_TIME 22 6838
TEST_TIME 23 6872
TEST_TIME 24 7096
TEST_TIME 25 7614
TEST_TIME 26 8575
TEST_TIME 27 8638
TEST_TIME 28 8641
TEST_TIME 29 9107
TEST_TIME 30 9189
TEST_TIME 31 10014
TEST_TIME 32 10329
TEST_TIME 33 10056
TEST_TIME 34 9922
TEST_TIME 35 9852
TEST_TIME 36 9684
TEST_TIME 37 9789
TEST_TIME 38 9706
TEST_TIME 39 9553
TEST_TIME 40 9605
TEST_TIME 41 9566
TEST_TIME 42 9479
TEST_TIME 43 9442
TEST_TIME 44 9523
TEST_TIME 45 9733
TEST_TIME 46 9658
TEST_TIME 47 9542
TEST_TIME 48 9480
TEST_TIME 49 9685
TEST_TIME 50 9757
TEST_TIME 51 9659
TEST_TIME 52 9551
TEST_TIME 53 9405
TEST_TIME 54 9447
TEST_TIME 55 9441
TEST_TIME 56 9525
TEST_TIME 57 9397
TEST_TIME 58 9376
TEST_TIME 59 9408
TEST_TIME 60 9320
TEST_TIME 61 9437
TEST_TIME 62 9380
TEST_TIME 63 9374
TEST_TIME 64 9280
TEST_TIME 65 9275
TEST_TIME 66 9286
TEST_TIME 67 9270
TEST_TIME 68 9283
TEST_TIME 69 9312
TEST_TIME 70 9358
TEST_TIME 71 9392
TEST_TIME 72 9449
TEST_TIME 73 9424
TEST_TIME 74 9377
TEST_TIME 75 9448
TEST_TIME 76 9556
TEST_TIME 77 9515
TEST_TIME 78 9506
TEST_TIME 79 9509
TEST_TIME 80 9452
TEST_TIME 81 9393
TEST_TIME 82 9384
TEST_TIME 83 9334
TEST_TIME 84 9323
TEST_TIME 85 9333
TEST_TIME 86 9276
TEST_TIME 87 9260
TEST_TIME 88 9212
TEST_TIME 89 9202
TEST_TIME 90 9184
TEST_TIME 91 9211
TEST_TIME 92 9191
TEST_TIME 93 9247
TEST_TIME 94 9245
TEST_TIME 95 9243
TEST_TIME 96 9251
TEST_TIME 97 9284
TEST_TIME 98 9251
TEST_TIME 99 9364
TEST_TIME 100 9377
TEST_TIME 101 9374
TEST_TIME 102 9412
TEST_TIME 103 9416
TEST_TIME 104 9440
TEST_TIME 105 9501
TEST_TIME 106 9445
TEST_TIME 107 9374
TEST_TIME 108 9394
TEST_TIME 109 9360
TEST_TIME 110 9377
TEST_TIME 111 9357
TEST_TIME 112 9331
TEST_TIME 113 9288
TEST_TIME 114 9302
TEST_TIME 115 9257
TEST_TIME 116 9220
TEST_TIME 117 9194
TEST_TIME 118 9195
TEST_TIME 119 9193
TEST_TIME 120 9155

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-10-2022 16:39:56 to 15-10-2022 16:39:59.

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 1514653 1514653 0 0.00 0 32368 6 0 0
doc_list_biud 1514649 1514649 0 0.00 0 33383 28 0 0
sp_add_invoice_to_stock 4982 4871 111 2.22 48 247058 17783 0 111
sp_cancel_adding_invoice 5810 5810 0 0.00 3 67986 776 0 0
sp_cancel_client_order 1761 1761 0 0.00 4 9902 321 0 0
sp_cancel_customer_reserve 564634 564634 0 0.00 0 33392 60 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 2 3669 78 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 2 971 50 0 0
sp_cancel_supplier_invoice 6420 6416 4 0.06 1 13610 769 0 4
sp_cancel_supplier_order 419 419 0 0.00 6 1233398 101962 0 0
sp_cancel_write_off 9692 9692 0 0.00 1 10548 110 0 0
sp_client_order 20085 20085 0 0.00 3 23064 361 0 0
sp_customer_reserve 449015 449015 0 0.00 0 142145 177 0 0
sp_fill_shopping_cart 30732 30732 0 0.00 1 13725 37 0 0
sp_fill_shopping_cart_clo_res 449010 449010 0 0.00 0 21210 13 0 0
sp_get_clo_for_invoice 4865 4865 0 0.00 0 35834 280 0 0
sp_kill_cost_storno 16600 16600 0 0.00 0 10546 57 0 0
sp_kill_qstorno_ret_qs2qd 1145795 1145795 0 0.00 0 33372 32 0 0
sp_kill_qty_storno 603358 603358 0 0.00 0 33383 69 0 0
sp_lock_selected_doc 20356 20356 0 0.00 0 7505 6 0 0
sp_make_cost_storno 27172 27172 0 0.00 0 32352 71 0 0
sp_multiply_rows_for_pdistr 27172 27172 0 0.00 0 26219 33 0 0
sp_multiply_rows_for_qdistr 20085 20085 0 0.00 0 11675 139 0 0
sp_pay_from_customer 6907 6907 0 0.00 4 26252 147 0 0
sp_pay_to_supplier 5639 5639 0 0.00 5 21533 166 0 0
sp_payment_common 12546 12546 0 0.00 1 21415 43 0 0
sp_qd_handle_on_cancel_clo 1761 1761 0 0.00 0 9896 297 0 0
sp_qd_handle_on_invoice_upd_sts 10675 10675 0 0.00 0 12679 312 0 0
sp_qd_handle_on_reserve_upd_sts 9761 9761 0 0.00 0 19386 62 0 0
sp_reserve_write_off 9761 9761 0 0.00 6 19408 200 0 0
sp_supplier_invoice 5372 5372 0 0.00 6 102023 3373 0 0
sp_supplier_order 5286 5286 0 0.00 5 47741 1280 0 0
srv_aggregate_perf_data 87 87 0 0.00 2591 517536 30173 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2590 204868 46869 0 0
srv_find_qd_qs_mism 1052188 1052188 0 0.00 0 9678 5 0 0
srv_make_invnt_saldo 544 544 0 0.00 30 149418 1297 0 0
srv_make_money_saldo 739 739 0 0.00 0 5752 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 145301 435305 319467 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4871 4871 0 0.00 0 17101 187 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 4772 113 0 0
t$perf-norm:sp_cancel_client_order 1761 1761 0 0.00 0 12370 19 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 4277 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 3327 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 4763 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 2503 29 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 21344 1618 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 2251 9 0 0
t$perf-norm:sp_client_order 20085 20085 0 0.00 0 15444 15 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 628 4 0 0
t$perf-norm:sp_pay_from_customer 6907 6907 0 0.00 0 7137 11 0 0
t$perf-norm:sp_pay_to_supplier 5639 5639 0 0.00 0 10236 13 0 0
t$perf-norm:sp_reserve_write_off 9761 9761 0 0.00 0 13258 16 0 0
t$perf-norm:sp_supplier_invoice 5372 5372 0 0.00 0 8704 24 0 0
t$perf-norm:sp_supplier_order 5286 5286 0 0.00 0 10383 17 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 41 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 9 1 0 0
t$perf-norm:srv_make_invnt_saldo 544 544 0 0.00 0 2455 24 0 0
t$perf-norm:srv_make_money_saldo 739 739 0 0.00 0 1084 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9950 9728 222 2.23 0 6971 19 0 222
v_all_customers 36796 36796 0 0.00 0 23 0 0 0
v_all_suppliers 29350 29350 0 0.00 0 79 1 0 0
v_all_wares 40170 40170 0 0.00 0 202 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 313 6 0 0
v_cancel_client_order 3522 3522 0 0.00 0 4631 5 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 3626 10 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 2623 6 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 386 4 0 0
v_cancel_supplier_invoice 786 778 8 1.01 0 338 13 0 8
v_cancel_supplier_order 838 838 0 0.00 0 2675 11 0 0
v_cancel_write_off 1960 1960 0 0.00 0 3816 15 0 0
v_max_id_clo_ord 5286 5286 0 0.00 0 3565 2 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 1392 8 0 0
v_max_id_ord_sup 5361 5361 0 0.00 0 3196 5 0 0
v_max_non_paid_invoice 1611 1611 0 0.00 0 3963 7 0 0
v_max_non_paid_realizn 3593 3593 0 0.00 0 3330 4 0 0
v_min_id_clo_ord 5286 5286 0 0.00 0 2795 6 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 890 4 0 0
v_min_id_ord_sup 5361 5361 0 0.00 0 8632 5 0 0
v_min_non_paid_invoice 5639 5639 0 0.00 0 21410 38 0 0
v_min_non_paid_realizn 6907 6907 0 0.00 0 19819 36 0 0
v_our_firm 10002 10002 0 0.00 0 20 1 0 0
v_reserve_write_off 19522 19522 0 0.00 0 5141 5 0 0
x_lock_depdocs_on_canc_invoice 11837 11837 0 0.00 0 19731 120 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 12425 389 0 0
x_make_qstorno_1000_1200 5286 5286 0 0.00 1 47726 1210 0 0
x_make_qstorno_1200_2000 5372 5372 0 0.00 3 102015 3239 0 0
x_make_qstorno_2100_3300 449015 449015 0 0.00 0 142142 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-10-2022 16:40:00 to 15-10-2022 16:40:04.

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:36 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:38 30895 8192000000 9912507936 10222309376 993430128 10.815 100 90 180 0 1023546768 5828064 24288576
14:11:33 15813 8192000000 9963512256 10284216320 1044453872 11.307 100 92 184 0 1076416832 8707376 23255584
14:12:52 15063 8192000000 9985143152 10317357056 1069053392 11.543 100 80 160 0 1098560304 9759904 19747008
14:14:14 8595 8192000000 10026705984 10373046272 1117292368 12.001 100 74 148 0 1145569248 10831904 17444976
14:15:31 4903 8192000000 10080830848 10442891264 1177462416 12.567 100 81 162 0 1208613872 11619424 19532032
14:16:38 2499 8192000000 10138570800 10507657216 1224163328 13.000 100 82 164 0 1255977552 14043360 17770864
14:17:43 1642 8192000000 10170940384 10551455744 1262378336 13.352 100 76 152 0 1291340992 12285904 16676752
14:18:46 1349 8192000000 10199398928 10583805952 1286516736 13.572 100 83 164 1 1318005808 13159520 18329552
14:19:50 5841 8192000000 10195655136 10590334976 1294709360 13.647 100 73 141 1 1321562736 10298000 16555376
14:20:56 10007 8192000000 10187860272 10596610048 1303029984 13.723 100 33 66 0 1316198480 5185344 7983152
14:22:09 8976 8192000000 10196440992 10606882816 1311619952 13.801 100 15 28 0 1320988160 6029312 3338896
14:23:21 16083 8192000000 10202406048 10619236352 1320619552 13.882 100 8 16 0 1327954448 5562320 1772576
14:24:39 34998 8192000000 10221338240 10640998400 1330814944 13.975 100 18 36 0 1340962400 5420608 4726848
14:26:29 10960 8192000000 10229321568 10650349568 1337590208 14.036 100 25 51 0 1349524048 6155296 5778544
14:27:40 11497 8192000000 10211944192 10635296768 1298598704 13.683 97 17 34 0 1308267328 5965536 3703088
14:28:53 11030 8192000000 10152629936 10561638400 1244933040 13.192 96 16 32 0 1253383920 5133792 3317088
14:30:07 29628 8192000000 10179146624 10583728128 1286339024 13.571 100 48 94 1 1302897296 4996016 11562256
14:31:37 6387 8192000000 10151225792 10538164224 1184486288 12.632 94 29 58 0 1196806752 5518656 6801808
14:32:46 7604 8192000000 10158379712 10556022784 1253865040 13.274 99 19 38 0 1263532832 5605024 4062768
14:33:58 11122 8192000000 10184937808 10592608256 1291698480 13.620 100 26 52 0 1303686880 5460768 6527632
14:35:10 23328 8192000000 10181819776 10602004480 1299099776 13.687 100 18 36 0 1308451280 5239872 4111632
14:36:35 7293 8192000000 10194348128 10615967744 1313475984 13.818 100 27 50 1 1323980224 4587536 5916704
14:37:43 5055 8192000000 10208416816 10629238784 1320820560 13.884 100 24 48 1 1331808416 5137984 5849872
14:38:51 11786 8192000000 10244475392 10657697792 1344278032 14.096 100 33 66 1 1357474752 6017104 7179616
14:40:05 28790 8192000000 10260250160 10678603776 1359469152 14.233 100 38 74 2 1373260512 5590080 8201280
14:41:39 20038 8192000000 10271075824 10700890112 1373334256 14.357 100 24 48 0 1389535632 10978128 5223248
14:43:12 6078 8192000000 10292347536 10713231360 1385824816 14.469 100 56 108 0 1407524880 9661344 12038720
14:44:25 25582 8192000000 10301333712 10730274816 1385263120 14.464 99 43 80 0 1407070256 12674368 9132768
14:46:05 24283 8192000000 10332130288 10758483968 1412685728 14.708 100 54 106 1 1440758800 15342992 12730080
14:47:32 15190 8192000000 10338314048 10765586432 1416493808 14.742 100 54 109 0 1452557728 23041952 13021968
14:49:03 29820 8192000000 10327856464 10763325440 1404898000 14.639 99 33 64 0 1435770160 22792976 8079184
14:50:38 12068 8192000000 10337898720 10773848064 1422883872 14.798 100 40 78 0 1457431072 24476240 10070960
14:51:55 9966 8192000000 10341055712 10767167488 1419612912 14.769 100 41 81 0 1454707824 24809440 10285472
14:53:10 3328 8192000000 10365126160 10785087488 1422724336 14.797 100 61 119 1 1475239472 37483696 15031440
14:54:19 3548 8192000000 10350207920 10760335360 1402015632 14.613 100 72 143 2 1458410576 37977760 18417184
14:55:36 4993 8192000000 10329926256 10742657024 1384706752 14.459 99 62 122 1 1437827504 38005568 15115184
14:56:50 4311 8192000000 10350269664 10765910016 1399856768 14.594 100 66 128 0 1458446784 42185216 16404800
14:58:01 2589 8192000000 10346126032 10753630208 1389927504 14.505 100 67 129 2 1450430944 44182576 16320864
14:59:10 2699 8192000000 10344462592 10746634240 1382299984 14.437 100 81 163 0 1448815440 46672592 19842864
15:00:17 5764 8192000000 10332991088 10749128704 1379289792 14.410 100 52 102 0 1440161488 46684432 14187264
15:01:36 5301 8192000000 10345674976 10749902848 1364363968 14.277 99 72 142 1 1437222576 54641360 18217248
15:02:48 3213 8192000000 10354147536 10766254080 1378064896 14.399 100 68 136 0 1459461408 62543488 18853024
15:03:54 16904 8192000000 10346590688 10768072704 1376400576 14.384 100 40 77 0 1449492016 62228688 10862752
15:05:14 14974 8192000000 10300157248 10729762816 1364909664 14.281 100 29 58 1 1410602256 38052096 7640496
15:07:04 13821 8192000000 10317968016 10747183104 1360708960 14.244 100 32 64 0 1421343696 51769312 8865424
15:08:37 31384 8192000000 10304627280 10737442816 1361744656 14.253 100 17 34 0 1408306480 41719600 4842224
15:10:10 76845 8192000000 10273534896 10716659712 1343046048 14.085 98 13 25 0 1360997248 15363536 2587664
15:12:41 33118 8192000000 10285638848 10735767552 1384667024 14.458 100 23 44 1 1401319424 11261728 5390672
15:14:15 78361 8192000000 10270115664 10724106240 1347205536 14.122 98 15 30 1 1363408528 12857360 3345632
15:16:41 95238 8192000000 10297236912 10742558720 1374989072 14.372 99 16 33 0 1389647712 10701072 3957568
15:19:31 30949 8192000000 10307168432 10754842624 1392824800 14.531 100 20 40 0 1407776976 10203376 4748800
15:21:42 21298 8192000000 10328951136 10781601792 1404999040 14.639 100 29 56 0 1431770992 20148224 6623728
15:23:39 110362 8192000000 10325911600 10771800064 1411037728 14.693 100 11 22 0 1431050432 17532576 2480128
15:26:30 9038 8192000000 10347827824 10784952320 1418202640 14.757 100 57 113 1 1445449776 13095456 14151680
15:27:42 116532 8192000000 10300025376 10753523712 1372541648 14.350 97 2 4 0 1391543968 18689104 313216
15:30:40 79842 8192000000 10323775856 10766508032 1408348544 14.669 100 55 109 0 1427642032 6440352 12853136
15:33:01 65669 8192000000 10318452912 10768261120 1374849104 14.370 97 25 50 1 1391589248 11075920 5664224
15:35:09 150043 8192000000 10320244688 10768031744 1371770368 14.343 97 20 40 0 1387626032 11160864 4694800
15:38:54 34748 8192000000 10326602640 10769854464 1400298048 14.598 100 55 109 1 1423641248 11773632 11569568
15:40:29 204868 8192000000 10274670608 10742456320 1303500464 13.727 93 19 36 0 1316115312 8723216 3891632
15:44:55 53699 8192000000 10275474096 10733686784 1332106736 13.986 96 20 38 0 1344196512 7549984 4539792
15:46:49 87151 8192000000 10298785728 10762776576 1335455344 14.016 96 15 30 0 1366220384 27194960 3570080
15:49:17 61046 8192000000 10259965472 10714787840 1307666032 13.765 96 18 36 0 1318907088 7537408 3703648
15:51:18 16426 8192000000 10262158208 10706485248 1352370944 14.169 100 23 47 0 1365795680 8423504 5001232
15:52:40 13649 8192000000 10274014064 10706522112 1347386848 14.124 100 49 98 0 1372348576 14085472 10876256
15:54:00 110172 8192000000 10258786000 10708037632 1280917024 13.521 95 6 10 1 1298963904 17083648 963232
15:56:58 34282 8192000000 10255900608 10691497984 1288185008 13.588 95 28 56 0 1310857088 16549792 6122288
15:58:36 81019 8192000000 10236042064 10677411840 1307617712 13.764 98 23 46 0 1322369776 9618864 5133200
16:01:07 48540 8192000000 10254472880 10710351872 1349844000 14.146 100 8 16 0 1364551760 13308816 1398944
16:02:56 59778 8192000000 10231442208 10686353408 1286876896 13.576 95 14 28 0 1300834848 10975760 2982192
16:05:02 65443 8192000000 10255141888 10709897216 1344950368 14.102 99 16 30 1 1356752288 7691072 4110848
16:07:15 97533 8192000000 10268976240 10726875136 1358085200 14.220 99 18 36 0 1376394848 14069072 4240576
16:09:55 66657 8192000000 10281495520 10738077696 1365715728 14.289 99 15 30 0 1379106496 10098672 3292096
16:12:13 44977 8192000000 10284274160 10735550464 1350793120 14.155 98 16 30 2 1361247104 7775680 2678304
16:13:59 19487 8192000000 10299582288 10744410112 1386316288 14.473 100 50 101 0 1408159696 10139072 11704336
16:15:38 35764 8192000000 10305033952 10754949120 1363403952 14.268 98 20 40 0 1378185840 10503984 4277904
16:17:14 67184 8192000000 10308874896 10769993728 1391533504 14.520 100 18 35 0 1410975200 15629584 3812112
16:19:25 59821 8192000000 10297125056 10759348224 1340587760 14.063 96 25 51 0 1363051744 16629712 5834272
16:21:26 24664 8192000000 10305998256 10751193088 1368604800 14.315 98 21 43 0 1381301568 8318736 4378032
16:22:53 129609 8192000000 10281618416 10733547520 1337302496 14.033 96 17 34 0 1354032720 12959936 3770288
16:26:04 93044 8192000000 10289964224 10735812608 1332445232 13.989 96 23 46 0 1350311008 12782368 5083408
16:28:38 53564 8192000000 10267112272 10733211648 1348101984 14.130 98 6 12 1 1358154368 8800800 1251584
16:30:43 76952 8192000000 10227969888 10668322816 1277104096 13.487 93 22 42 0 1290577712 8858480 4615136
16:33:21 41607 8192000000 10244492384 10699923456 1339388272 14.052 99 16 32 0 1349259392 6156400 3714720
16:35:05 93228 8192000000 10257346112 10722058240 1335637616 14.018 98 12 22 0 1346954560 9040560 2276384
16:38:24 14055 8192000000 9033166464 9252519936 205697008 2.449 16 4 8 0 208250896 1951456 602432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-10-2022 16:40:06 to 15-10-2022 16:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-10-2022 16:40:22 to 15-10-2022 16:40:24.

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          239368
MON$OLDEST_ACTIVE               239369
MON$OLDEST_SNAPSHOT             239369
MON$NEXT_TRANSACTION            239369
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                       2036896
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
:390: 
:391: mscs-test-161.budzdorov.ru    Sat Oct 15 16:38:43 2022
:392:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:393: 
--- end of diff output ---

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

15.10.22 16:40:25. 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-10-15 16:40:28 No severe PSQL-related problems occured
15.10.22 16:40:29. End of report.