Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.08.2022 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 16092884 kB
MemAvailable 30958268 kB
Buffers 0 kB
Cached 15654476 kB
SwapCached 2508 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-08-11 19:38:21
DTS_END                         2022-08-11 19:38:21.8840
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/49220
TRN_ID                          242669
ATT_ID                          658
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: 9215.33 26267 1105840
customer order: creation 170.14 356 20417
customer order: refuse 14.46 317 1735
order to supplier: creation 46.98 1233 5637
order to supplier: removal 3.27 104660 392
invoice (draft): creation 46.33 3232 5559
invoice (draft): removal 50.89 810 6107
invoice accept: apply 42.81 17258 5137
invoice accept: cancel 46.43 761 5572
customer reserve: creation 3923.51 172 470821
customer reserve: removal 4577.08 59 549250
realization accept: apply 83.50 153 10020
realization accept: cancel 80.57 97 9668
payment from customer: creation 59.04 87 7085
payment from customer: removal 6.14 81 737
payment to supplier: creation 48.45 108 5814
payment to supplier: removal 3.41 56 409
service: total inventory turnovers 5.26 907 631
service: total monetary turnovers 6.16 23 739
service: aggregate perf. data 0.88 19040 106
service: refresh index statistics 0.03 375936 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-08-2022 19:39:43 to 11-08-2022 19:39:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4101
TEST_TIME 2 4420
TEST_TIME 3 4708
TEST_TIME 4 4557
TEST_TIME 5 5553
TEST_TIME 6 5095
TEST_TIME 7 5074
TEST_TIME 8 5210
TEST_TIME 9 5373
TEST_TIME 10 5446
TEST_TIME 11 6062
TEST_TIME 12 6324
TEST_TIME 13 6400
TEST_TIME 14 7000
TEST_TIME 15 7259
TEST_TIME 16 7633
TEST_TIME 17 7695
TEST_TIME 18 7668
TEST_TIME 19 8367
TEST_TIME 20 8851
TEST_TIME 21 9164
TEST_TIME 22 9232
TEST_TIME 23 9238
TEST_TIME 24 9384
TEST_TIME 25 9898
TEST_TIME 26 11295
TEST_TIME 27 11140
TEST_TIME 28 11107
TEST_TIME 29 11043
TEST_TIME 30 11153
TEST_TIME 31 11019
TEST_TIME 32 10834
TEST_TIME 33 10637
TEST_TIME 34 10404
TEST_TIME 35 10188
TEST_TIME 36 10200
TEST_TIME 37 10081
TEST_TIME 38 9978
TEST_TIME 39 9874
TEST_TIME 40 9806
TEST_TIME 41 9840
TEST_TIME 42 9758
TEST_TIME 43 9713
TEST_TIME 44 9796
TEST_TIME 45 9736
TEST_TIME 46 9577
TEST_TIME 47 9518
TEST_TIME 48 9432
TEST_TIME 49 9449
TEST_TIME 50 9347
TEST_TIME 51 9347
TEST_TIME 52 9258
TEST_TIME 53 9397
TEST_TIME 54 9357
TEST_TIME 55 9435
TEST_TIME 56 9482
TEST_TIME 57 9473
TEST_TIME 58 9466
TEST_TIME 59 9511
TEST_TIME 60 9435
TEST_TIME 61 9439
TEST_TIME 62 9509
TEST_TIME 63 9549
TEST_TIME 64 9512
TEST_TIME 65 9525
TEST_TIME 66 9474
TEST_TIME 67 9447
TEST_TIME 68 9392
TEST_TIME 69 9339
TEST_TIME 70 9395
TEST_TIME 71 9420
TEST_TIME 72 9359
TEST_TIME 73 9316
TEST_TIME 74 9287
TEST_TIME 75 9299
TEST_TIME 76 9237
TEST_TIME 77 9299
TEST_TIME 78 9270
TEST_TIME 79 9288
TEST_TIME 80 9364
TEST_TIME 81 9357
TEST_TIME 82 9347
TEST_TIME 83 9319
TEST_TIME 84 9472
TEST_TIME 85 9443
TEST_TIME 86 9424
TEST_TIME 87 9437
TEST_TIME 88 9531
TEST_TIME 89 9437
TEST_TIME 90 9381
TEST_TIME 91 9347
TEST_TIME 92 9406
TEST_TIME 93 9339
TEST_TIME 94 9286
TEST_TIME 95 9292
TEST_TIME 96 9263
TEST_TIME 97 9323
TEST_TIME 98 9287
TEST_TIME 99 9276
TEST_TIME 100 9310
TEST_TIME 101 9359
TEST_TIME 102 9401
TEST_TIME 103 9364
TEST_TIME 104 9343
TEST_TIME 105 9442
TEST_TIME 106 9410
TEST_TIME 107 9435
TEST_TIME 108 9429
TEST_TIME 109 9445
TEST_TIME 110 9370
TEST_TIME 111 9370
TEST_TIME 112 9317
TEST_TIME 113 9293
TEST_TIME 114 9254
TEST_TIME 115 9329
TEST_TIME 116 9309
TEST_TIME 117 9274
TEST_TIME 118 9273
TEST_TIME 119 9273
TEST_TIME 120 9218

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-08-2022 19:39:47 to 11-08-2022 19:39:50.

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 1541594 1541594 0 0.00 0 17508 6 0 0
doc_list_biud 1541594 1541594 0 0.00 0 40315 27 0 0
sp_add_invoice_to_stock 5265 5137 128 2.43 26 182168 17259 0 128
sp_cancel_adding_invoice 5572 5572 0 0.00 5 66784 762 0 0
sp_cancel_client_order 1735 1735 0 0.00 4 8236 318 0 0
sp_cancel_customer_reserve 549250 549250 0 0.00 0 18213 59 0 0
sp_cancel_pay_from_customer 737 737 0 0.00 2 5253 81 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 2 1548 56 0 0
sp_cancel_supplier_invoice 6117 6107 10 0.16 2 18242 810 0 10
sp_cancel_supplier_order 392 392 0 0.00 5 1086545 104661 0 0
sp_cancel_write_off 9668 9668 0 0.00 1 9863 98 0 0
sp_client_order 20417 20417 0 0.00 3 85307 357 0 0
sp_customer_reserve 470821 470821 0 0.00 0 89489 173 0 0
sp_fill_shopping_cart 31593 31593 0 0.00 1 14391 40 0 0
sp_fill_shopping_cart_clo_res 470815 470815 0 0.00 0 24723 14 0 0
sp_get_clo_for_invoice 5131 5131 0 0.00 0 27264 327 0 0
sp_kill_cost_storno 16386 16386 0 0.00 0 10183 50 0 0
sp_kill_qstorno_ret_qs2qd 1114667 1114667 0 0.00 0 18194 32 0 0
sp_kill_qty_storno 587875 587875 0 0.00 0 40314 69 0 0
sp_lock_selected_doc 20839 20839 0 0.00 0 7475 4 0 0
sp_make_cost_storno 28050 28050 0 0.00 0 17173 43 0 0
sp_multiply_rows_for_pdistr 28050 28050 0 0.00 0 5650 24 0 0
sp_multiply_rows_for_qdistr 20417 20417 0 0.00 0 79692 154 0 0
sp_pay_from_customer 7085 7085 0 0.00 4 7954 88 0 0
sp_pay_to_supplier 5814 5814 0 0.00 4 8700 109 0 0
sp_payment_common 12899 12899 0 0.00 1 5108 18 0 0
sp_qd_handle_on_cancel_clo 1735 1735 0 0.00 0 8228 289 0 0
sp_qd_handle_on_invoice_upd_sts 10703 10703 0 0.00 0 40313 302 0 0
sp_qd_handle_on_reserve_upd_sts 10020 10020 0 0.00 0 13025 59 0 0
sp_reserve_write_off 10020 10020 0 0.00 5 13090 153 0 0
sp_supplier_invoice 5559 5559 0 0.00 5 72305 3232 0 0
sp_supplier_order 5637 5637 0 0.00 4 43752 1233 0 0
srv_aggregate_perf_data 106 106 0 0.00 2161 265912 19040 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1372 140144 46047 0 0
srv_find_qd_qs_mism 1057722 1057722 0 0.00 0 17490 5 0 0
srv_make_invnt_saldo 631 631 0 0.00 35 41268 907 0 0
srv_make_money_saldo 739 739 0 0.00 1 1392 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 266484 598298 375937 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5137 5137 0 0.00 0 27082 168 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 4757 89 0 0
t$perf-norm:sp_cancel_client_order 1735 1735 0 0.00 0 1383 8 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 3965 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 737 737 0 0.00 0 6732 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 300 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 364 364 0 0.00 0 1009 8 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 30048 1625 0 0
t$perf-norm:sp_cancel_write_off 929 929 0 0.00 0 7389 25 0 0
t$perf-norm:sp_client_order 20417 20417 0 0.00 0 8257 14 0 0
t$perf-norm:sp_customer_reserve 1053 1053 0 0.00 0 5660 11 0 0
t$perf-norm:sp_pay_from_customer 7085 7085 0 0.00 0 3054 8 0 0
t$perf-norm:sp_pay_to_supplier 5814 5814 0 0.00 0 5611 13 0 0
t$perf-norm:sp_reserve_write_off 10020 10020 0 0.00 0 7173 11 0 0
t$perf-norm:sp_supplier_invoice 5559 5559 0 0.00 0 4880 17 0 0
t$perf-norm:sp_supplier_order 5637 5637 0 0.00 0 5140 15 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 278 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 144 3 0 0
t$perf-norm:srv_make_invnt_saldo 631 631 0 0.00 0 19689 55 0 0
t$perf-norm:srv_make_money_saldo 739 739 0 0.00 0 681 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10518 10262 256 2.43 0 4161 18 0 256
v_all_customers 36856 36856 0 0.00 0 28 1 0 0
v_all_suppliers 30602 30602 0 0.00 0 22 1 0 0
v_all_wares 40834 40834 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 1181 8 0 0
v_cancel_client_order 3470 3470 0 0.00 0 3497 7 0 0
v_cancel_customer_prepayment 1474 1474 0 0.00 0 3796 11 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 0 3886 9 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 805 6 0 0
v_cancel_supplier_invoice 748 728 20 2.67 0 1811 20 0 20
v_cancel_supplier_order 784 784 0 0.00 0 1090 5 0 0
v_cancel_write_off 1858 1858 0 0.00 0 2993 16 0 0
v_max_id_clo_ord 5637 5637 0 0.00 0 1605 1 0 0
v_max_id_clo_res 1053 1053 0 0.00 0 2422 9 0 0
v_max_id_ord_sup 5538 5538 0 0.00 0 6246 7 0 0
v_max_non_paid_invoice 1688 1688 0 0.00 0 429 2 0 0
v_max_non_paid_realizn 3871 3871 0 0.00 0 1262 3 0 0
v_min_id_clo_ord 5637 5637 0 0.00 0 2608 6 0 0
v_min_id_clo_res 1053 1053 0 0.00 0 2809 10 0 0
v_min_id_ord_sup 5538 5538 0 0.00 0 6433 9 0 0
v_min_non_paid_invoice 5814 5814 0 0.00 0 4313 12 0 0
v_min_non_paid_realizn 7085 7085 0 0.00 0 5103 12 0 0
v_our_firm 10406 10406 0 0.00 0 25 1 0 0
v_reserve_write_off 20040 20040 0 0.00 0 4017 8 0 0
x_lock_depdocs_on_canc_invoice 11315 11315 0 0.00 0 13989 112 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 11336 351 0 0
x_make_qstorno_1000_1200 5637 5637 0 0.00 1 43734 1162 0 0
x_make_qstorno_1200_2000 5559 5559 0 0.00 2 72128 3075 0 0
x_make_qstorno_2100_3300 470821 470821 0 0.00 0 89487 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-08-2022 19:39:51 to 11-08-2022 19:39:55.

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:25 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:26 34274 8192000000 9896500320 10205589504 975219440 10.638 100 87 173 0 1004556208 6109840 23226928
17:11:12 22951 8192000000 9953731232 10276306944 1039939760 11.264 100 87 174 0 1070594944 8460848 22194336
17:12:42 14702 8192000000 9986601920 10323492864 1072320048 11.574 100 80 160 1 1102119424 9805344 19994032
17:14:00 10060 8192000000 10053751936 10402693120 1137566480 12.193 100 86 172 0 1169263872 11875968 19821424
17:15:15 4121 8192000000 10097754480 10457968640 1179564784 12.586 100 83 162 0 1210030912 12145216 18320912
17:16:21 3470 8192000000 10138518672 10507952128 1228053920 13.036 100 86 172 0 1260258080 14078240 18125920
17:17:28 2072 8192000000 10139851312 10524606464 1240976704 13.155 100 66 128 0 1268254784 13283504 13994576
17:18:35 2333 8192000000 10186255424 10569932800 1275906416 13.476 100 81 160 0 1306483296 13014960 17561920
17:19:39 26739 8192000000 10188950128 10588868608 1295257408 13.652 100 54 108 0 1317134400 9343824 12533168
17:21:08 4555 8192000000 10195111776 10605604864 1303131472 13.724 100 35 68 0 1323346704 13077136 7138096
17:22:14 3620 8192000000 10209658432 10616881152 1311465744 13.799 100 47 94 0 1329980320 7999024 10515552
17:23:18 11089 8192000000 10197855984 10614550528 1315005008 13.831 100 24 48 0 1325965200 5363632 5596560
17:24:32 41717 8192000000 10225530080 10633768960 1322539808 13.900 100 25 50 0 1333891104 5479088 5872208
17:26:14 5450 8192000000 10233245472 10651332608 1329190576 13.960 100 26 52 0 1345615104 11161216 5263312
17:27:20 7610 8192000000 10217500320 10638966784 1318488096 13.863 99 20 40 0 1332507392 9550848 4468448
17:28:28 43706 8192000000 10177609920 10593341440 1271341760 13.434 98 17 32 0 1281186128 5037808 4806560
17:30:13 4710 8192000000 10162508992 10568679424 1257754080 13.309 99 35 71 1 1271905376 6017808 8133488
17:31:18 7388 8192000000 10149218192 10543849472 1213781216 12.904 97 27 54 2 1233897920 14260304 5856400
17:32:26 11334 8192000000 10148457376 10549202944 1246745680 13.208 99 24 47 0 1257870160 5308400 5816080
17:33:38 21266 8192000000 10167186064 10582175744 1276478000 13.481 100 23 47 0 1291286656 9424656 5384000
17:34:59 6611 8192000000 10188401952 10600804352 1295452112 13.654 100 29 57 1 1307596608 5846640 6297856
17:36:06 11524 8192000000 10199752384 10612936704 1304548176 13.737 100 23 46 2 1314973968 4900592 5525200
17:37:19 46745 8192000000 10200207472 10624172032 1312902096 13.812 100 20 40 1 1322995584 5369904 4723584
17:39:26 20018 8192000000 10245359776 10660556800 1341300032 14.069 100 45 87 1 1358896784 8402000 9194752
17:40:52 4247 8192000000 10270196480 10683293696 1358855520 14.227 100 62 124 1 1388227648 15844624 13527504
17:42:08 38929 8192000000 10290983712 10712502272 1373734032 14.360 100 45 90 0 1403196288 18932160 10530096
17:43:49 10322 8192000000 10291685024 10725748736 1385230160 14.463 100 31 62 1 1413820848 20678096 7912592
17:45:05 6723 8192000000 10312748608 10738696192 1395245680 14.553 100 45 90 0 1427768496 21920624 10602192
17:46:19 7701 8192000000 10324589840 10754220032 1402551728 14.618 100 39 79 1 1436207632 24181136 9474768
17:47:31 5365 8192000000 10341422400 10767970304 1408808512 14.673 100 54 107 1 1451801328 30481824 12510992
17:48:43 3870 8192000000 10337602400 10753015808 1394721664 14.548 100 68 135 0 1444456960 32921776 16813520
17:49:53 3996 8192000000 10330027104 10748923904 1392690272 14.530 100 61 121 0 1440708208 33135408 14882528
17:51:04 7906 8192000000 10352575296 10768715776 1389721456 14.503 99 68 135 0 1445780960 39174048 16885456
17:52:18 2990 8192000000 10370084080 10789834752 1408585088 14.671 100 72 140 1 1472471776 46005040 17881648
17:53:27 1366 8192000000 10368517712 10786578432 1411746960 14.699 100 72 144 0 1474732880 44943232 18042688
17:54:32 3242 8192000000 10362566976 10785456128 1409365792 14.678 100 58 116 4 1471233792 45908432 15959568
17:55:41 5141 8192000000 10360639808 10779086848 1399385904 14.590 100 70 140 1 1471162976 53678432 18098640
17:56:51 4941 8192000000 10371653616 10781396992 1395031760 14.551 100 73 146 0 1474695440 59994192 19669488
17:58:03 3239 8192000000 10356450304 10779918336 1396506944 14.564 100 49 100 0 1464962032 54201280 14253808
17:59:10 9349 8192000000 10310246656 10730299392 1358142656 14.221 99 35 69 1 1414814352 47039760 9631936
18:00:26 16811 8192000000 10294751376 10720415744 1333803168 14.002 99 22 44 0 1388021664 47930656 6287840
18:01:46 16552 8192000000 10290622496 10715525120 1331343328 13.979 99 23 46 1 1389337088 51165136 6828624
18:03:05 23064 8192000000 10308368896 10739142656 1336568240 14.026 100 22 44 0 1411775152 67604448 7602464
18:04:31 28599 8192000000 10277295184 10728456192 1340882128 14.065 99 27 51 2 1386646864 38962800 6801936
18:06:33 47012 8192000000 10269657456 10708987904 1357101632 14.211 100 27 54 0 1380656064 17143248 6411184
18:08:20 112241 8192000000 10236375024 10683957248 1279237344 13.506 94 7 15 0 1291665904 10514288 1914272
18:11:14 85672 8192000000 10261203392 10707107840 1356319280 14.204 100 13 26 1 1366318336 7204592 2794464
18:13:42 38435 8192000000 10298531472 10731753472 1371820752 14.343 100 55 106 3 1394053888 9832112 12401024
18:15:28 65140 8192000000 10293406592 10745344000 1386622208 14.476 100 13 26 0 1399596032 10153920 2819904
18:18:16 88014 8192000000 10302182912 10757726208 1385411360 14.465 99 47 94 1 1412145136 15601968 11131808
18:20:52 9604 8192000000 10311108768 10762354688 1402071952 14.613 100 37 72 0 1426177232 15389008 8716272
18:22:05 58284 8192000000 10281677120 10742841344 1367774384 14.307 98 10 20 1 1382864048 12912864 2176800
18:24:23 23061 8192000000 10280851792 10743119872 1383958944 14.452 100 16 33 1 1396754880 8671408 4124528
18:25:46 82178 8192000000 10283883264 10746015744 1383606272 14.449 99 16 32 0 1397803952 9994992 4202688
18:28:09 42035 8192000000 10269548336 10733101056 1356186128 14.203 98 7 14 0 1365485504 7790576 1508800
18:30:11 59171 8192000000 10298206976 10747383808 1365186736 14.284 98 25 50 0 1385058816 14226064 5646016
18:32:29 58683 8192000000 10312736368 10763350016 1397733616 14.575 100 12 24 0 1411324400 10856384 2734400
18:34:29 54350 8192000000 10299124480 10761469952 1370005248 14.327 98 13 26 0 1386008096 13003792 2999056
18:36:27 30198 8192000000 10296376272 10751565824 1383422144 14.447 99 24 46 0 1400385760 11673968 5289648
18:38:00 24453 8192000000 10288070224 10733096960 1346826704 14.119 96 27 53 0 1359879552 7442368 5610480
18:39:25 57285 8192000000 10303894880 10767691776 1379226064 14.410 99 33 66 0 1405717952 19800512 6691376
18:41:23 55562 8192000000 10283940976 10730364928 1332022016 13.985 96 15 30 0 1349342816 14427264 2893536
18:43:18 49342 8192000000 10272877824 10714087424 1330089488 13.968 97 38 74 0 1350503360 11763120 8650752
18:45:37 95108 8192000000 10273042384 10724077568 1337222112 14.032 97 20 40 0 1354235888 12305504 4708272
18:48:28 83351 8192000000 10259624688 10720968704 1332804896 13.992 97 12 24 1 1353883504 17983456 3095152
18:50:52 35736 8192000000 10253335408 10703613952 1351346576 14.160 99 19 37 1 1367892944 12408640 4137728
18:52:36 78222 8192000000 10250491344 10710929408 1329010464 13.958 97 7 14 0 1343157104 12642288 1504352
18:55:03 27722 8192000000 10256510800 10704060416 1352540272 14.170 100 12 21 1 1364321184 9370352 2410560
18:56:34 48055 8192000000 10286524064 10729455616 1370803872 14.334 100 35 70 0 1391013632 12568128 7641632
18:58:23 88729 8192000000 10257412528 10709385216 1298767376 13.684 95 13 26 0 1314120080 12339632 3013072
19:00:52 55342 8192000000 10268268912 10709827584 1351358912 14.160 99 26 52 0 1375080736 17948976 5772848
19:02:48 74440 8192000000 10287318928 10730373120 1371963680 14.345 100 34 68 0 1397340688 17829392 7547616
19:05:28 55159 8192000000 10300322544 10761990144 1370040144 14.327 99 35 71 0 1399224608 21102592 8081872
19:07:24 96035 8192000000 10303904096 10742808576 1351971200 14.165 97 30 58 1 1369182576 10766896 6444480
19:10:01 59518 8192000000 10285971408 10753769472 1388061296 14.489 99 14 28 0 1398930832 7783392 3086144
19:12:21 98363 8192000000 10310005920 10772008960 1389993744 14.506 99 12 24 0 1405872704 13460192 2418768
19:15:10 77734 8192000000 10288975280 10740273152 1352936016 14.174 97 11 23 0 1364442048 9077664 2428368
19:17:31 38453 8192000000 10308486000 10754531328 1384383696 14.456 100 35 71 0 1401640864 9545184 7711984
19:19:10 114458 8192000000 10286345536 10744086528 1345438608 14.106 97 6 10 0 1363997088 17565264 993216
19:22:05 40004 8192000000 10305683808 10748784640 1386814512 14.477 100 36 72 1 1407366784 11995008 8557264
19:23:50 140144 8192000000 10295933968 10744737792 1282610032 13.537 92 7 14 0 1297411648 13040176 1761440
19:27:13 98066 8192000000 10292458592 10741665792 1370986528 14.336 99 13 26 0 1391167968 17402272 2779168
19:30:08 35796 8192000000 10303064400 10744664064 1387723744 14.486 100 39 77 1 1407091168 10748224 8619200
19:31:44 60846 8192000000 10297390576 10754408448 1391278176 14.517 100 20 40 0 1414750704 18322144 5150384
19:34:42 83678 8192000000 10256174272 10708361216 1336114640 14.022 98 14 28 0 1351038656 11776288 3147728
19:37:14 50822 8192000000 10266159024 10717655040 1343963056 14.093 98 17 34 1 1355959360 7863920 4132384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-08-2022 19:39:57 to 11-08-2022 19:40:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-08-2022 19:40:12 to 11-08-2022 19:40:14.

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          241357
MON$OLDEST_ACTIVE               242781
MON$OLDEST_SNAPSHOT             242781
MON$NEXT_TRANSACTION            242781
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                       2014592
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.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
:91: 
:92: mscs-test-161.budzdorov.ru    Thu Aug 11 19:38:24 2022
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:94: 
:95: 
:96: mscs-test-161.budzdorov.ru    Thu Aug 11 19:38:27 2022
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:98: 
--- end of diff output ---

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

11.08.22 19:40:15. 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-08-11 19:40:18 No severe PSQL-related problems occured
11.08.22 19:40:19. End of report.