Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.10.2022 01: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 15563948 kB
MemAvailable 30514024 kB
Buffers 0 kB
Cached 16177988 kB
SwapCached 2052 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-19 01:38:28
DTS_END                         2022-10-19 01:38:28.7200
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/52104
TRN_ID                          246966
ATT_ID                          699
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: 9073.40 20621 1088808
customer order: creation 182.30 352 21876
customer order: refuse 15.65 316 1878
order to supplier: creation 48.07 1142 5768
order to supplier: removal 3.55 91529 426
invoice (draft): creation 48.48 2936 5817
invoice (draft): removal 50.38 795 6045
invoice accept: apply 43.41 16841 5209
invoice accept: cancel 44.57 790 5348
customer reserve: creation 3975.76 169 477091
customer reserve: removal 4359.52 59 523142
realization accept: apply 87.86 150 10543
realization accept: cancel 77.74 101 9329
payment from customer: creation 61.63 99 7395
payment from customer: removal 6.12 68 734
payment to supplier: creation 51.93 102 6231
payment to supplier: removal 3.82 63 458
service: total inventory turnovers 4.95 929 594
service: total monetary turnovers 6.77 25 812
service: aggregate perf. data 0.90 19073 108
service: refresh index statistics 0.03 276873 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-10-2022 01:39:59 to 19-10-2022 01:40:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5860
TEST_TIME 2 5790
TEST_TIME 3 6037
TEST_TIME 4 5798
TEST_TIME 5 5750
TEST_TIME 6 5357
TEST_TIME 7 5142
TEST_TIME 8 5097
TEST_TIME 9 5310
TEST_TIME 10 5418
TEST_TIME 11 5441
TEST_TIME 12 5631
TEST_TIME 13 5717
TEST_TIME 14 6092
TEST_TIME 15 6273
TEST_TIME 16 6484
TEST_TIME 17 6804
TEST_TIME 18 7371
TEST_TIME 19 7364
TEST_TIME 20 7788
TEST_TIME 21 8417
TEST_TIME 22 8936
TEST_TIME 23 9155
TEST_TIME 24 9276
TEST_TIME 25 9337
TEST_TIME 26 9650
TEST_TIME 27 10553
TEST_TIME 28 10480
TEST_TIME 29 10442
TEST_TIME 30 10466
TEST_TIME 31 10333
TEST_TIME 32 10360
TEST_TIME 33 10144
TEST_TIME 34 9928
TEST_TIME 35 9903
TEST_TIME 36 10024
TEST_TIME 37 9825
TEST_TIME 38 9730
TEST_TIME 39 9648
TEST_TIME 40 9766
TEST_TIME 41 9812
TEST_TIME 42 9684
TEST_TIME 43 9611
TEST_TIME 44 9603
TEST_TIME 45 9569
TEST_TIME 46 9441
TEST_TIME 47 9330
TEST_TIME 48 9391
TEST_TIME 49 9352
TEST_TIME 50 9289
TEST_TIME 51 9301
TEST_TIME 52 9181
TEST_TIME 53 9136
TEST_TIME 54 9237
TEST_TIME 55 9209
TEST_TIME 56 9227
TEST_TIME 57 9273
TEST_TIME 58 9308
TEST_TIME 59 9317
TEST_TIME 60 9404
TEST_TIME 61 9480
TEST_TIME 62 9451
TEST_TIME 63 9541
TEST_TIME 64 9557
TEST_TIME 65 9516
TEST_TIME 66 9547
TEST_TIME 67 9416
TEST_TIME 68 9355
TEST_TIME 69 9329
TEST_TIME 70 9305
TEST_TIME 71 9401
TEST_TIME 72 9359
TEST_TIME 73 9306
TEST_TIME 74 9287
TEST_TIME 75 9247
TEST_TIME 76 9208
TEST_TIME 77 9266
TEST_TIME 78 9306
TEST_TIME 79 9308
TEST_TIME 80 9307
TEST_TIME 81 9288
TEST_TIME 82 9249
TEST_TIME 83 9298
TEST_TIME 84 9404
TEST_TIME 85 9357
TEST_TIME 86 9356
TEST_TIME 87 9375
TEST_TIME 88 9394
TEST_TIME 89 9315
TEST_TIME 90 9276
TEST_TIME 91 9268
TEST_TIME 92 9203
TEST_TIME 93 9173
TEST_TIME 94 9130
TEST_TIME 95 9179
TEST_TIME 96 9202
TEST_TIME 97 9209
TEST_TIME 98 9222
TEST_TIME 99 9202
TEST_TIME 100 9241
TEST_TIME 101 9227
TEST_TIME 102 9225
TEST_TIME 103 9262
TEST_TIME 104 9244
TEST_TIME 105 9294
TEST_TIME 106 9327
TEST_TIME 107 9318
TEST_TIME 108 9313
TEST_TIME 109 9344
TEST_TIME 110 9283
TEST_TIME 111 9226
TEST_TIME 112 9222
TEST_TIME 113 9194
TEST_TIME 114 9144
TEST_TIME 115 9152
TEST_TIME 116 9153
TEST_TIME 117 9148
TEST_TIME 118 9130
TEST_TIME 119 9109
TEST_TIME 120 9076

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-10-2022 01:40:03 to 19-10-2022 01:40:06.

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 1532014 1532014 0 0.00 0 19647 6 0 0
doc_list_biud 1532014 1532014 0 0.00 0 59914 26 0 0
sp_add_invoice_to_stock 5269 5209 60 1.13 35 204450 16841 0 60
sp_cancel_adding_invoice 5348 5348 0 0.00 4 60178 791 0 0
sp_cancel_client_order 1878 1878 0 0.00 4 11489 316 0 0
sp_cancel_customer_reserve 523142 523142 0 0.00 0 59916 59 0 0
sp_cancel_pay_from_customer 734 734 0 0.00 2 9120 68 0 0
sp_cancel_pay_to_supplier 458 458 0 0.00 3 2104 64 0 0
sp_cancel_supplier_invoice 6050 6045 5 0.08 1 20126 796 0 5
sp_cancel_supplier_order 426 426 0 0.00 5 1204917 91530 0 0
sp_cancel_write_off 9329 9329 0 0.00 1 14354 101 0 0
sp_client_order 21876 21876 0 0.00 3 21255 353 0 0
sp_customer_reserve 477091 477091 0 0.00 0 65520 169 0 0
sp_fill_shopping_cart 33452 33452 0 0.00 1 13447 34 0 0
sp_fill_shopping_cart_clo_res 477086 477086 0 0.00 0 17409 13 0 0
sp_get_clo_for_invoice 5203 5203 0 0.00 0 20753 274 0 0
sp_kill_cost_storno 15869 15869 0 0.00 0 19641 48 0 0
sp_kill_qstorno_ret_qs2qd 1062084 1062084 0 0.00 0 59912 32 0 0
sp_kill_qty_storno 561914 561914 0 0.00 0 59914 69 0 0
sp_lock_selected_doc 21851 21851 0 0.00 0 9003 6 0 0
sp_make_cost_storno 29372 29372 0 0.00 0 10318 44 0 0
sp_multiply_rows_for_pdistr 29372 29372 0 0.00 0 8125 24 0 0
sp_multiply_rows_for_qdistr 21876 21876 0 0.00 0 19757 159 0 0
sp_pay_from_customer 7395 7395 0 0.00 4 10323 99 0 0
sp_pay_to_supplier 6231 6231 0 0.00 4 10125 102 0 0
sp_payment_common 13625 13625 0 0.00 1 6782 18 0 0
sp_qd_handle_on_cancel_clo 1878 1878 0 0.00 0 11481 289 0 0
sp_qd_handle_on_invoice_upd_sts 10551 10551 0 0.00 0 19730 310 0 0
sp_qd_handle_on_reserve_upd_sts 10543 10543 0 0.00 0 13568 49 0 0
sp_reserve_write_off 10543 10543 0 0.00 5 13587 150 0 0
sp_supplier_invoice 5817 5817 0 0.00 5 83748 2936 0 0
sp_supplier_order 5768 5768 0 0.00 5 22377 1142 0 0
srv_aggregate_perf_data 108 108 0 0.00 2344 478813 19074 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1533 108363 39625 0 0
srv_find_qd_qs_mism 1039561 1039561 0 0.00 0 15535 6 0 0
srv_make_invnt_saldo 594 594 0 0.00 29 28503 930 0 0
srv_make_money_saldo 812 812 0 0.00 1 1824 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 112393 447974 276873 0 0
t$perf-abend:sp_add_invoice_to_stock 60 60 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5209 5209 0 0.00 0 20231 156 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 4305 95 0 0
t$perf-norm:sp_cancel_client_order 1878 1878 0 0.00 0 7329 16 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 1965 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 734 734 0 0.00 0 2575 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 458 458 0 0.00 0 330 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 476 476 0 0.00 0 1975 12 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 21764 1441 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 3425 15 0 0
t$perf-norm:sp_client_order 21876 21876 0 0.00 0 9883 13 0 0
t$perf-norm:sp_customer_reserve 1096 1096 0 0.00 0 1348 5 0 0
t$perf-norm:sp_pay_from_customer 7395 7395 0 0.00 0 4148 9 0 0
t$perf-norm:sp_pay_to_supplier 6231 6231 0 0.00 0 24009 14 0 0
t$perf-norm:sp_reserve_write_off 10543 10543 0 0.00 0 5003 9 0 0
t$perf-norm:sp_supplier_invoice 5817 5817 0 0.00 0 7900 13 0 0
t$perf-norm:sp_supplier_order 5768 5768 0 0.00 0 5946 15 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 1317 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 3 0 0 0
t$perf-norm:srv_make_invnt_saldo 594 594 0 0.00 0 6057 33 0 0
t$perf-norm:srv_make_money_saldo 812 812 0 0.00 0 1148 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 10524 10404 120 1.14 0 3687 18 0 120
v_all_customers 39450 39450 0 0.00 0 197 1 0 0
v_all_suppliers 32033 32033 0 0.00 0 19 1 0 0
v_all_wares 43752 43752 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 1864 14 0 0
v_cancel_client_order 3756 3756 0 0.00 0 1712 5 0 0
v_cancel_customer_prepayment 1468 1468 0 0.00 0 750 4 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 184 3 0 0
v_cancel_payment_to_supplier 916 916 0 0.00 0 1049 7 0 0
v_cancel_supplier_invoice 962 952 10 1.03 0 2208 15 0 10
v_cancel_supplier_order 852 852 0 0.00 0 1191 4 0 0
v_cancel_write_off 2026 2026 0 0.00 0 4611 14 0 0
v_max_id_clo_ord 5768 5768 0 0.00 0 268 1 0 0
v_max_id_clo_res 1096 1096 0 0.00 0 8490 20 0 0
v_max_id_ord_sup 5807 5807 0 0.00 0 3587 4 0 0
v_max_non_paid_invoice 1788 1788 0 0.00 0 360 2 0 0
v_max_non_paid_realizn 4087 4087 0 0.00 0 5089 4 0 0
v_min_id_clo_ord 5768 5768 0 0.00 0 5296 7 0 0
v_min_id_clo_res 1096 1096 0 0.00 0 6521 27 0 0
v_min_id_ord_sup 5807 5807 0 0.00 0 4244 5 0 0
v_min_non_paid_invoice 6229 6229 0 0.00 0 3649 9 0 0
v_min_non_paid_realizn 7395 7395 0 0.00 0 6411 12 0 0
v_our_firm 10918 10918 0 0.00 0 19 1 0 0
v_reserve_write_off 21086 21086 0 0.00 0 3861 6 0 0
x_lock_depdocs_on_canc_invoice 10917 10917 0 0.00 0 31194 114 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 7231 328 0 0
x_make_qstorno_1000_1200 5768 5768 0 0.00 1 22354 1077 0 0
x_make_qstorno_1200_2000 5817 5817 0 0.00 2 83742 2812 0 0
x_make_qstorno_2100_3300 477091 477091 0 0.00 0 65517 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-10-2022 01:40:07 to 19-10-2022 01:40:11.

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
23:08:32 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:33 17421 8192000000 9887377536 10190667776 972183152 10.608 100 89 178 0 1001405136 5404864 23817120
23:10:57 20152 8192000000 9937619792 10255081472 1022352784 11.095 100 87 174 0 1051639248 7024896 22261568
23:12:20 11258 8192000000 9978309120 10306564096 1062858208 11.484 100 88 175 0 1092526848 8633760 21034880
23:13:37 9362 8192000000 10019265584 10360315904 1104467312 11.880 100 84 168 0 1135203184 10620304 20115568
23:14:50 8368 8192000000 10081413056 10435604480 1162144992 12.423 100 84 168 0 1193702368 11924672 19632704
23:16:00 2842 8192000000 10111237728 10474594304 1200655024 12.782 100 80 157 1 1230530800 12716784 17158992
23:17:06 1737 8192000000 10152239056 10524495872 1234579152 13.096 100 82 163 0 1266910208 13892240 18438816
23:18:10 2428 8192000000 10175627968 10557923328 1266413616 13.389 100 84 164 0 1299168832 14332832 18422384
23:19:17 1885 8192000000 10207268144 10591367168 1295395376 13.653 100 84 166 3 1328170352 13882640 18892336
23:20:21 2166 8192000000 10209236208 10602536960 1302365968 13.717 100 71 141 0 1328612048 10414368 15831712
23:21:25 8170 8192000000 10195562416 10610184192 1310678176 13.792 100 22 45 0 1326360320 10452736 5229408
23:22:35 8938 8192000000 10212446336 10624540672 1321965664 13.895 100 26 53 1 1333924768 6382928 5576176
23:23:44 37153 8192000000 10230349040 10639343616 1328295824 13.952 100 56 107 0 1346606896 5383760 12927312
23:25:22 5100 8192000000 10228245232 10646781952 1333371664 13.998 100 19 36 1 1346470928 9384368 3714896
23:26:28 5309 8192000000 10221044464 10642546688 1324234784 13.915 99 11 22 0 1331943056 5600736 2107536
23:27:34 54447 8192000000 10217204592 10638684160 1300791936 13.702 97 23 46 0 1310895232 4710016 5393280
23:29:29 8436 8192000000 10156513408 10566119424 1226775056 13.024 94 27 54 0 1239551200 5964848 6811296
23:30:39 9889 8192000000 10149556352 10552299520 1223259952 12.992 96 28 55 0 1235697280 5760560 6676768
23:31:50 50226 8192000000 10149168816 10525532160 1232171088 13.074 99 77 155 0 1257592080 6688432 18732560
23:33:41 6579 8192000000 10157955040 10554716160 1260968272 13.339 100 29 58 1 1273534176 6554224 6011680
23:34:48 8721 8192000000 10170669216 10574282752 1266720352 13.392 99 23 46 0 1277343104 4943792 5678960
23:35:57 9554 8192000000 10197725856 10602995712 1298234256 13.679 100 18 35 1 1307020704 4948752 3837696
23:37:08 25608 8192000000 10189184656 10607038464 1304763088 13.739 100 23 46 1 1315559312 5139872 5656352
23:38:38 3104 8192000000 10236250496 10642591744 1331656128 13.982 100 51 99 0 1348415984 6089888 10669968
23:39:44 6271 8192000000 10254234544 10655916032 1342277168 14.078 100 71 140 3 1367655392 9570480 15807744
23:40:55 7005 8192000000 10265046000 10680094720 1357367008 14.214 100 50 100 2 1380394720 12168336 10859376
23:42:19 30099 8192000000 10282991840 10707857408 1376854336 14.388 100 37 74 0 1400658928 15132112 8672480
23:43:54 3910 8192000000 10300834672 10724052992 1390900496 14.514 100 41 81 1 1414593264 13823968 9868800
23:45:02 23664 8192000000 10308716080 10731089920 1392835184 14.531 100 43 86 0 1424124240 21342224 9946832
23:46:39 8988 8192000000 10323601984 10755080192 1412274448 14.704 100 41 83 2 1445591056 22928608 10388000
23:47:53 12741 8192000000 10344185040 10767511552 1419793968 14.771 100 43 86 0 1456152352 24936800 11421584
23:49:15 2183 8192000000 10367846864 10784722944 1430415504 14.865 100 75 148 0 1475829696 28308272 17105920
23:50:22 3057 8192000000 10362471632 10779496448 1420161776 14.774 100 67 131 0 1469085328 32703008 16220544
23:51:28 8880 8192000000 10343116544 10760110080 1402281136 14.615 100 66 132 1 1452989616 34413728 16294752
23:52:44 1532 8192000000 10315381392 10724425728 1370425856 14.331 100 71 142 1 1422506240 34426496 17653888
23:53:52 2389 8192000000 10361627312 10770784256 1405390032 14.643 100 81 161 3 1464417488 39306112 19721344
23:54:58 5265 8192000000 10286929280 10691121152 1328676784 13.955 98 59 117 1 1386718672 42966432 15075456
23:56:07 5912 8192000000 10324628560 10742284288 1375300624 14.375 100 52 104 0 1436192944 46420960 14471360
23:57:21 4026 8192000000 10326480032 10732552192 1360456400 14.241 100 78 154 1 1429749936 48992416 20301120
23:58:29 8495 8192000000 10320912448 10741714944 1360598304 14.243 100 51 102 0 1430815104 56134320 14082480
23:59:44 2178 8192000000 10358092960 10765611008 1379393584 14.411 100 82 160 0 1453506736 54132224 19980928
00:00:49 7598 8192000000 10322061760 10747756544 1384801264 14.459 100 32 64 0 1420618000 27459680 8357056
00:02:00 20924 8192000000 10305763520 10738872320 1355623312 14.198 98 25 50 0 1392807632 30149552 7034768
00:03:22 20059 8192000000 10287534848 10718339072 1337951392 14.039 98 19 38 0 1375436048 32233280 5251376
00:04:44 37671 8192000000 10288291280 10748600320 1355324336 14.195 100 12 24 0 1399409472 40400656 3684480
00:06:25 24046 8192000000 10260730800 10705813504 1336833088 14.029 98 21 42 0 1354203968 12052496 5318384
00:08:03 81085 8192000000 10282221616 10729902080 1346058624 14.112 98 8 14 0 1364500848 17057760 1384464
00:10:35 51455 8192000000 10292871360 10722947072 1370049440 14.327 100 29 58 1 1386690752 10054368 6586944
00:12:28 108363 8192000000 10276957952 10734235648 1357924032 14.219 98 32 64 0 1379476880 13816576 7736272
00:15:23 26186 8192000000 10318309088 10754179072 1395620064 14.556 100 38 76 2 1414634400 10177808 8836528
00:16:51 48754 8192000000 10317269408 10755743744 1383648320 14.449 99 35 68 0 1404775152 13376192 7750640
00:18:49 27200 8192000000 10289691632 10749632512 1385743008 14.468 100 17 33 0 1402482384 13082432 3656944
00:20:17 37734 8192000000 10294392896 10746249216 1383099312 14.444 99 23 46 1 1398526832 10524768 4902752
00:21:57 42568 8192000000 10281995104 10743144448 1372916672 14.353 99 13 26 0 1385518800 9011408 3590720
00:23:44 47499 8192000000 10275122336 10736263168 1374027472 14.363 99 12 24 1 1384007456 7161072 2818912
00:25:32 58920 8192000000 10296836096 10749579264 1381614608 14.431 99 19 39 0 1395351408 9524768 4212032
00:27:32 40791 8192000000 10281652224 10738155520 1375860688 14.380 99 13 24 0 1384907072 6452416 2593968
00:29:24 62801 8192000000 10287284864 10757771264 1399433872 14.590 100 12 24 0 1410414272 8183760 2796640
00:32:18 52081 8192000000 10278103040 10739691520 1339645632 14.054 96 12 23 0 1352720128 10682352 2392144
00:34:11 19562 8192000000 10284779360 10717790208 1364769216 14.280 100 49 95 0 1386381520 10257776 11354528
00:35:32 28463 8192000000 10280188432 10725584896 1339909104 14.057 97 27 51 0 1353473792 7859568 5705120
00:37:21 32429 8192000000 10300590688 10748035072 1367547056 14.305 99 38 76 1 1394485776 18380576 8558144
00:38:55 90790 8192000000 10270928064 10728095744 1312480192 13.809 95 6 12 0 1327054784 13531408 1043184
00:41:26 77064 8192000000 10263761040 10715262976 1332394112 13.989 97 13 26 1 1348037024 13107312 2535600
00:43:48 68021 8192000000 10262261392 10725765120 1368196032 14.311 100 22 44 0 1385716112 12609008 4911072
00:46:34 57142 8192000000 10253231920 10691612672 1305455520 13.745 95 35 70 0 1323860896 10694720 7710656
00:49:55 57878 8192000000 10236021552 10692976640 1322358768 13.898 98 12 24 0 1332958320 7577856 3021696
00:51:54 45656 8192000000 10256631472 10703597568 1350452896 14.152 100 14 26 0 1361241376 7774704 3013776
00:53:40 65980 8192000000 10278778288 10732326912 1353372928 14.178 99 12 24 0 1367910336 11873440 2663968
00:56:55 41943 8192000000 10270111952 10722504704 1367121088 14.301 100 21 42 0 1381245040 9252048 4871904
00:58:50 20195 8192000000 10265435856 10710437888 1337579056 14.036 98 30 60 1 1352191744 7663856 6948832
01:00:13 14223 8192000000 10287664640 10729172992 1359753568 14.235 99 37 74 0 1380969552 13302496 7913488
01:01:31 82457 8192000000 10279203744 10746580992 1350350000 14.151 98 17 34 0 1369207264 14904528 3952736
01:03:55 40677 8192000000 10299427504 10735456256 1358794592 14.227 99 22 44 0 1375481712 12098608 4588512
01:05:39 81458 8192000000 10282887072 10751328256 1368922880 14.317 99 4 8 0 1385770240 16164480 682880
01:08:17 36760 8192000000 10287578176 10744946688 1377785744 14.397 99 20 40 0 1390673712 8847920 4040048
01:09:53 53693 8192000000 10294305120 10759024640 1388786112 14.495 99 9 18 0 1397688752 6918320 1984320
01:11:47 55551 8192000000 10303382864 10765025280 1367228512 14.302 98 5 10 0 1378419520 10311088 879920
01:13:43 64505 8192000000 10279533856 10749157376 1325413808 13.926 95 9 18 0 1343315856 15607824 2294224
01:15:48 35923 8192000000 10283514272 10732171264 1346740768 14.118 98 18 36 0 1359956256 9185888 4029600
01:17:27 52515 8192000000 10246806512 10700382208 1307469216 13.763 95 14 26 0 1319260800 8726480 3065104
01:19:28 36740 8192000000 10286403056 10736115712 1353714544 14.181 98 27 52 1 1370225200 9376928 7133728
01:21:05 102213 8192000000 10300830528 10745397248 1335947536 14.021 96 21 42 0 1352874128 12074480 4852112
01:24:04 95131 8192000000 10297120416 10750148608 1341490896 14.071 97 8 16 0 1360084480 16919344 1674240
01:26:43 47975 8192000000 10290954096 10760011776 1383119760 14.444 100 16 32 0 1407839776 20954288 3765728
01:28:35 57893 8192000000 10275875488 10719285248 1328536656 13.954 96 24 48 0 1344062384 9914624 5611104
01:30:34 73419 8192000000 10258010752 10724229120 1354849808 14.191 99 9 18 0 1366605984 9884464 1871712
01:33:32 80686 8192000000 10268282304 10734891008 1350316144 14.150 98 21 43 0 1363752112 8553952 4882016
01:35:56 63995 8192000000 10265064416 10721800192 1324714080 13.919 97 15 30 0 1341966592 13736544 3515968
01:38:00 38438 8192000000 9146320048 9403146240 247921520 2.937 19 7 14 0 260280448 10829680 1529248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-10-2022 01:40:13 to 19-10-2022 01:40:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 60 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-10-2022 01:40:30 to 19-10-2022 01:40:32.

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          246965
MON$OLDEST_ACTIVE               247343
MON$OLDEST_SNAPSHOT             247343
MON$NEXT_TRANSACTION            247343
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                       2017472
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.33635 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33635 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33635 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33635 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 ---
:129: 
:130: mscs-test-161.budzdorov.ru    Wed Oct 19 01:38:55 2022
:131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:132: 
--- end of diff output ---

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

19.10.22 01:40:33. 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-19 01:40:35 No severe PSQL-related problems occured
19.10.22 01:40:37. End of report.