Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.10.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 15517260 kB
MemAvailable 30466440 kB
Buffers 0 kB
Cached 16223800 kB
SwapCached 2088 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-25 19:38:29
DTS_END                         2022-10-25 19:38:29.9130
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/39856
TRN_ID                          243793
ATT_ID                          661
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: 9110.17 19746 1093221
customer order: creation 175.58 351 21070
customer order: refuse 15.40 376 1848
order to supplier: creation 45.68 1208 5482
order to supplier: removal 3.11 105364 373
invoice (draft): creation 46.79 3648 5615
invoice (draft): removal 49.58 821 5949
invoice accept: apply 43.47 16946 5216
invoice accept: cancel 44.48 792 5338
customer reserve: creation 3981.00 168 477720
customer reserve: removal 4414.65 58 529758
realization accept: apply 84.77 175 10172
realization accept: cancel 73.84 118 8861
payment from customer: creation 60.33 120 7239
payment from customer: removal 5.42 95 650
payment to supplier: creation 50.33 124 6040
payment to supplier: removal 3.51 83 421
service: total inventory turnovers 4.99 1099 599
service: total monetary turnovers 6.38 41 766
service: aggregate perf. data 0.83 22943 100
service: refresh index statistics 0.03 240399 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-10-2022 19:39:55 to 25-10-2022 19:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2979
TEST_TIME 2 3471
TEST_TIME 3 3954
TEST_TIME 4 3813
TEST_TIME 5 3826
TEST_TIME 6 4438
TEST_TIME 7 4298
TEST_TIME 8 4556
TEST_TIME 9 5093
TEST_TIME 10 5744
TEST_TIME 11 6089
TEST_TIME 12 6212
TEST_TIME 13 6756
TEST_TIME 14 7178
TEST_TIME 15 7763
TEST_TIME 16 7689
TEST_TIME 17 8014
TEST_TIME 18 8205
TEST_TIME 19 8495
TEST_TIME 20 8294
TEST_TIME 21 8306
TEST_TIME 22 8161
TEST_TIME 23 8575
TEST_TIME 24 9014
TEST_TIME 25 9313
TEST_TIME 26 9648
TEST_TIME 27 9964
TEST_TIME 28 10633
TEST_TIME 29 10867
TEST_TIME 30 10605
TEST_TIME 31 10600
TEST_TIME 32 10467
TEST_TIME 33 10260
TEST_TIME 34 10017
TEST_TIME 35 9925
TEST_TIME 36 9925
TEST_TIME 37 9841
TEST_TIME 38 9738
TEST_TIME 39 9821
TEST_TIME 40 9701
TEST_TIME 41 9646
TEST_TIME 42 9704
TEST_TIME 43 9543
TEST_TIME 44 9551
TEST_TIME 45 9564
TEST_TIME 46 9445
TEST_TIME 47 9442
TEST_TIME 48 9443
TEST_TIME 49 9316
TEST_TIME 50 9292
TEST_TIME 51 9204
TEST_TIME 52 9189
TEST_TIME 53 9117
TEST_TIME 54 9108
TEST_TIME 55 9190
TEST_TIME 56 9178
TEST_TIME 57 9145
TEST_TIME 58 9143
TEST_TIME 59 9205
TEST_TIME 60 9227
TEST_TIME 61 9276
TEST_TIME 62 9309
TEST_TIME 63 9310
TEST_TIME 64 9345
TEST_TIME 65 9326
TEST_TIME 66 9430
TEST_TIME 67 9386
TEST_TIME 68 9276
TEST_TIME 69 9224
TEST_TIME 70 9200
TEST_TIME 71 9106
TEST_TIME 72 9129
TEST_TIME 73 9050
TEST_TIME 74 8964
TEST_TIME 75 9042
TEST_TIME 76 9072
TEST_TIME 77 9190
TEST_TIME 78 9283
TEST_TIME 79 9215
TEST_TIME 80 9269
TEST_TIME 81 9267
TEST_TIME 82 9291
TEST_TIME 83 9302
TEST_TIME 84 9303
TEST_TIME 85 9335
TEST_TIME 86 9331
TEST_TIME 87 9430
TEST_TIME 88 9371
TEST_TIME 89 9330
TEST_TIME 90 9310
TEST_TIME 91 9271
TEST_TIME 92 9273
TEST_TIME 93 9228
TEST_TIME 94 9188
TEST_TIME 95 9121
TEST_TIME 96 9216
TEST_TIME 97 9186
TEST_TIME 98 9184
TEST_TIME 99 9208
TEST_TIME 100 9215
TEST_TIME 101 9225
TEST_TIME 102 9243
TEST_TIME 103 9198
TEST_TIME 104 9226
TEST_TIME 105 9284
TEST_TIME 106 9300
TEST_TIME 107 9339
TEST_TIME 108 9314
TEST_TIME 109 9339
TEST_TIME 110 9331
TEST_TIME 111 9254
TEST_TIME 112 9247
TEST_TIME 113 9220
TEST_TIME 114 9232
TEST_TIME 115 9192
TEST_TIME 116 9169
TEST_TIME 117 9128
TEST_TIME 118 9173
TEST_TIME 119 9164
TEST_TIME 120 9111

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-10-2022 19:39:59 to 25-10-2022 19:40:02.

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 1534601 1534601 0 0.00 0 21559 6 0 0
doc_list_biud 1534601 1534601 0 0.00 0 67571 26 0 0
sp_add_invoice_to_stock 5315 5216 99 1.86 26 171435 16947 0 99
sp_cancel_adding_invoice 5338 5338 0 0.00 5 73273 792 0 0
sp_cancel_client_order 1848 1848 0 0.00 4 25070 376 0 0
sp_cancel_customer_reserve 529758 529758 0 0.00 0 67572 58 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 3 11046 96 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 4742 84 0 0
sp_cancel_supplier_invoice 5960 5949 11 0.18 2 17248 821 0 11
sp_cancel_supplier_order 373 373 0 0.00 5 1047863 105365 0 0
sp_cancel_write_off 8861 8861 0 0.00 1 8425 119 0 0
sp_client_order 21070 21070 0 0.00 3 32905 351 0 0
sp_customer_reserve 477720 477720 0 0.00 0 33568 168 0 0
sp_fill_shopping_cart 32154 32154 0 0.00 1 33598 45 0 0
sp_fill_shopping_cart_clo_res 477715 477715 0 0.00 0 21226 14 0 0
sp_get_clo_for_invoice 5211 5211 0 0.00 0 27919 356 0 0
sp_kill_cost_storno 15270 15270 0 0.00 0 9767 58 0 0
sp_kill_qstorno_ret_qs2qd 1074699 1074699 0 0.00 0 67569 32 0 0
sp_kill_qty_storno 567510 567510 0 0.00 0 67571 69 0 0
sp_lock_selected_doc 21150 21150 0 0.00 0 7968 6 0 0
sp_make_cost_storno 28662 28662 0 0.00 0 21454 57 0 0
sp_multiply_rows_for_pdistr 28662 28662 0 0.00 0 9677 26 0 0
sp_multiply_rows_for_qdistr 21070 21070 0 0.00 0 12122 149 0 0
sp_pay_from_customer 7239 7239 0 0.00 4 20510 120 0 0
sp_pay_to_supplier 6040 6040 0 0.00 4 13688 125 0 0
sp_payment_common 13279 13279 0 0.00 1 11510 33 0 0
sp_qd_handle_on_cancel_clo 1848 1848 0 0.00 0 14165 328 0 0
sp_qd_handle_on_invoice_upd_sts 10549 10549 0 0.00 0 58131 332 0 0
sp_qd_handle_on_reserve_upd_sts 10172 10172 0 0.00 0 20527 58 0 0
sp_reserve_write_off 10172 10172 0 0.00 6 21841 175 0 0
sp_supplier_invoice 5615 5615 0 0.00 5 100201 3648 0 0
sp_supplier_order 5482 5482 0 0.00 4 38218 1209 0 0
srv_aggregate_perf_data 100 100 0 0.00 2461 513792 22943 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1087 131354 45878 0 0
srv_find_qd_qs_mism 1043889 1043889 0 0.00 0 11436 5 0 0
srv_make_invnt_saldo 599 599 0 0.00 24 38895 1100 0 0
srv_make_money_saldo 766 766 0 0.00 1 5722 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 124718 426959 240400 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5216 5216 0 0.00 0 11871 155 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 8375 144 0 0
t$perf-norm:sp_cancel_client_order 1848 1848 0 0.00 0 2700 13 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 555 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 9127 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 4791 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 1075 17 0 0
t$perf-norm:sp_cancel_supplier_order 373 373 0 0.00 0 26915 1604 0 0
t$perf-norm:sp_cancel_write_off 971 971 0 0.00 0 3282 9 0 0
t$perf-norm:sp_client_order 21070 21070 0 0.00 0 8263 16 0 0
t$perf-norm:sp_customer_reserve 1049 1049 0 0.00 0 2240 7 0 0
t$perf-norm:sp_pay_from_customer 7239 7239 0 0.00 0 5764 9 0 0
t$perf-norm:sp_pay_to_supplier 6040 6040 0 0.00 0 9808 10 0 0
t$perf-norm:sp_reserve_write_off 10173 10173 0 0.00 0 18744 14 0 0
t$perf-norm:sp_supplier_invoice 5615 5615 0 0.00 0 3032 13 0 0
t$perf-norm:sp_supplier_order 5482 5482 0 0.00 0 2111 11 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 476 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2551 42 0 0
t$perf-norm:srv_make_invnt_saldo 599 599 0 0.00 0 1016 20 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 1857 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10620 10422 198 1.86 0 29051 25 0 198
v_all_customers 38490 38490 0 0.00 0 21 1 0 0
v_all_suppliers 30764 30764 0 0.00 0 26 1 0 0
v_all_wares 42140 42140 0 0.00 0 83 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 183 6 0 0
v_cancel_client_order 3696 3696 0 0.00 0 24831 17 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 3001 13 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 1588 6 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 2443 11 0 0
v_cancel_supplier_invoice 878 856 22 2.50 0 2579 15 0 22
v_cancel_supplier_order 746 746 0 0.00 0 29099 44 0 0
v_cancel_write_off 1942 1942 0 0.00 0 5866 20 0 0
v_max_id_clo_ord 5481 5481 0 0.00 0 110 1 0 0
v_max_id_clo_res 1049 1049 0 0.00 0 4213 18 0 0
v_max_id_ord_sup 5601 5601 0 0.00 0 6500 4 0 0
v_max_non_paid_invoice 1740 1740 0 0.00 0 681 3 0 0
v_max_non_paid_realizn 3827 3827 0 0.00 0 2423 5 0 0
v_min_id_clo_ord 5481 5481 0 0.00 0 2433 5 0 0
v_min_id_clo_res 1049 1049 0 0.00 0 162 2 0 0
v_min_id_ord_sup 5601 5601 0 0.00 0 4904 8 0 0
v_min_non_paid_invoice 6040 6040 0 0.00 0 11499 21 0 0
v_min_non_paid_realizn 7239 7239 0 0.00 0 10792 30 0 0
v_our_firm 10474 10474 0 0.00 0 134 1 0 0
v_reserve_write_off 20344 20344 0 0.00 0 18460 8 0 0
x_lock_depdocs_on_canc_invoice 10859 10859 0 0.00 0 16496 110 0 0
x_lock_depdocs_on_canc_sup_ord 373 373 0 0.00 0 8431 330 0 0
x_make_qstorno_1000_1200 5482 5482 0 0.00 1 38206 1135 0 0
x_make_qstorno_1200_2000 5615 5615 0 0.00 2 99946 3463 0 0
x_make_qstorno_2100_3300 477720 477720 0 0.00 0 29891 152 0 0

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

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:33 5 8192000000 8808059648 8822624256 12683584 0.154 6 1 2 0 12788592 62224 42784
17:09:34 18601 8192000000 9897078080 10201583616 979804384 10.682 100 93 186 0 1011024512 6302976 24917152
17:11:06 13991 8192000000 9943691632 10265894912 1028251152 11.152 100 88 176 0 1059339312 8554768 22533392
17:12:25 15911 8192000000 9983202544 10314207232 1066064992 11.514 100 92 185 0 1099549424 10210336 23274096
17:13:53 9565 8192000000 10031460784 10375602176 1112502192 11.956 100 85 170 0 1144676032 11415152 20758688
17:15:09 5316 8192000000 10075003008 10431025152 1164089840 12.442 100 84 166 0 1196014016 12599168 19325008
17:16:17 4233 8192000000 10128316960 10494681088 1209782816 12.867 100 84 165 0 1241396032 13368736 18244480
17:17:23 1699 8192000000 10156933136 10533351424 1248029744 13.220 100 85 170 0 1281363440 14609792 18723904
17:18:26 1800 8192000000 10187058272 10568314880 1275342672 13.470 100 86 171 0 1307882832 13821888 18718272
17:19:29 18017 8192000000 10210031648 10603233280 1300974720 13.704 100 63 128 0 1325941856 9684128 15283008
17:20:48 4866 8192000000 10211101264 10615914496 1314145072 13.824 100 37 74 0 1329934880 7538480 8251328
17:21:55 11349 8192000000 10209373712 10621706240 1323579360 13.909 100 20 38 0 1333936016 5574272 4782384
17:23:07 15379 8192000000 10213703536 10631446528 1329621520 13.964 100 22 44 0 1339706576 5691360 4393696
17:24:23 32970 8192000000 10222425824 10644889600 1332477856 13.990 100 36 71 0 1346338240 5607088 8253296
17:25:56 10474 8192000000 10220553504 10647883776 1336729360 14.028 100 28 56 2 1348624016 5504688 6389968
17:27:07 12910 8192000000 10212816656 10636607488 1319859120 13.875 99 13 26 0 1328443760 5749376 2835264
17:28:22 54099 8192000000 10201030624 10613923840 1309670896 13.783 99 29 58 0 1322196192 5725808 6799488
17:30:16 6258 8192000000 10163622784 10561847296 1253546688 13.271 98 48 95 2 1272697664 8519504 10631472
17:31:25 7783 8192000000 10120236944 10517286912 1218725264 12.950 98 22 44 0 1229686896 5365888 5595744
17:32:33 7321 8192000000 10142374128 10539143168 1235903648 13.108 99 25 50 1 1247787056 5639456 6243952
17:33:44 25625 8192000000 10149879360 10555019264 1256137616 13.295 100 16 32 0 1265107056 5482592 3486848
17:35:12 6872 8192000000 10184864128 10591014912 1288164784 13.587 100 26 52 0 1299799312 5866688 5767840
17:36:20 4602 8192000000 10189211984 10597142528 1295667568 13.656 100 24 49 2 1306989488 5563792 5758128
17:37:29 12956 8192000000 10198110624 10610368512 1303154208 13.724 100 13 26 0 1310901792 4783984 2963600
17:38:43 72595 8192000000 10230389200 10642505728 1318424528 13.862 100 31 62 0 1335580736 10498144 6658064
17:40:58 8873 8192000000 10249376768 10666328064 1346715808 14.118 100 37 73 0 1365663040 10436608 8510624
17:42:21 18123 8192000000 10272102032 10689998848 1364198944 14.275 100 42 82 0 1389431408 15345968 9886496
17:43:58 39764 8192000000 10291339008 10717831168 1377508432 14.394 100 34 66 0 1404642416 18611344 8522640
17:45:44 4251 8192000000 10290129872 10709159936 1373702032 14.360 99 42 83 1 1402292192 18292832 10297328
17:46:56 14948 8192000000 10319489712 10735095808 1380087872 14.417 99 64 126 1 1416243072 21520976 14634224
17:48:18 2300 8192000000 10335696864 10752946176 1403972656 14.630 100 66 132 0 1443949472 24907568 15069248
17:49:26 4787 8192000000 10327547984 10739679232 1376360768 14.384 99 60 117 2 1418529616 27753872 14414976
17:50:34 4675 8192000000 10330730608 10744832000 1381403360 14.429 100 66 131 0 1433949840 36499552 16046928
17:51:43 1530 8192000000 10326561408 10733899776 1370740032 14.334 100 77 148 1 1428154032 37659616 19754384
17:52:48 3107 8192000000 10312177952 10716389376 1359136832 14.230 100 75 148 0 1415604304 38343856 18123616
17:53:55 1086 8192000000 10308391952 10718502912 1368262256 14.311 100 75 145 3 1415806528 30246304 17297968
17:55:03 1419 8192000000 10337969792 10737971200 1372442480 14.349 99 86 167 2 1432619408 39243104 20933824
17:56:07 1661 8192000000 10336296800 10739884032 1378791536 14.406 100 78 157 0 1436496912 38674848 19030528
17:57:16 1985 8192000000 10312678192 10716659712 1362979312 14.264 100 66 128 0 1411718480 32717296 16021872
17:58:23 22106 8192000000 10299722080 10719256576 1326476144 13.935 97 34 65 0 1371850752 37032096 8342512
18:00:22 13711 8192000000 10342149344 10763124736 1382203312 14.436 100 36 72 1 1439075760 46228528 10643920
18:01:43 9717 8192000000 10332094896 10759520256 1365390416 14.286 99 40 79 1 1434433200 56738176 12304608
18:02:58 11196 8192000000 10324379584 10754850816 1379197744 14.409 100 44 88 0 1436744896 45698384 11848768
18:04:11 24227 8192000000 10312081824 10747105280 1355451856 14.197 99 26 50 0 1406379216 43676672 7250688
18:05:40 35099 8192000000 10308245360 10745884672 1356164592 14.203 100 25 50 0 1413460624 50003056 7292976
18:07:45 38059 8192000000 10277179264 10721656832 1366655056 14.297 100 10 18 1 1383565840 14932416 1978368
18:09:50 88101 8192000000 10282958240 10728349696 1375095376 14.373 100 39 79 0 1392801312 8872448 8833488
18:12:19 42350 8192000000 10317878816 10754318336 1393126944 14.534 100 44 89 0 1416095328 12841120 10127264
18:14:10 37357 8192000000 10298032976 10758279168 1399598016 14.591 100 19 38 0 1419331312 15130048 4603248
18:15:54 82396 8192000000 10315205568 10768977920 1387965712 14.488 99 22 44 0 1411541568 18390944 5184912
18:18:59 60783 8192000000 10301858048 10757582848 1370497872 14.332 98 14 28 0 1388010848 13847632 3665344
18:21:00 46786 8192000000 10296944576 10762174464 1398133824 14.578 100 13 26 0 1412359328 11293040 2932464
18:23:11 66345 8192000000 10296360336 10761039872 1381097536 14.426 99 15 31 0 1397673424 13310816 3265072
18:25:18 42328 8192000000 10280110224 10745593856 1379914560 14.416 99 26 53 0 1396493456 9632704 6946192
18:27:07 97947 8192000000 10265390368 10728525824 1352843552 14.173 97 17 35 0 1365094752 8275264 3975936
18:31:31 74306 8192000000 10304263152 10762543104 1403017872 14.622 100 24 48 0 1418574224 9347392 6208960
18:34:06 37863 8192000000 10315860624 10772496384 1409626992 14.681 100 30 60 1 1424565824 8387168 6551664
18:35:46 59720 8192000000 10267824560 10722250752 1329752000 13.965 95 16 32 0 1344730656 11550464 3428192
18:37:56 44882 8192000000 10282239616 10726682624 1312832496 13.812 94 30 60 0 1328280016 8954128 6493392
18:39:41 97507 8192000000 10254909920 10704285696 1273803776 13.456 93 14 28 0 1289002352 12449232 2749344
18:43:20 131349 8192000000 10258843184 10722775040 1291824000 13.621 94 26 52 0 1316714928 18385184 6505744
18:46:34 47741 8192000000 10265206464 10710478848 1342027216 14.076 98 35 73 1 1358971120 8672688 8271216
18:48:25 58938 8192000000 10259712704 10718572544 1340906784 14.066 98 19 38 0 1356850992 11605248 4338960
18:51:23 50393 8192000000 10265806544 10726834176 1315615552 13.837 96 8 16 0 1338280544 20810128 1854864
18:53:23 62244 8192000000 10252496992 10696122368 1299773120 13.693 95 18 36 0 1325938752 21630128 4535504
18:55:25 66701 8192000000 10262818896 10723209216 1344913616 14.102 99 13 26 0 1370816336 22990656 2912064
18:57:33 35097 8192000000 10282401904 10716626944 1357021232 14.211 99 40 80 1 1374612864 8462256 9129376
18:59:08 38099 8192000000 10278633984 10727010304 1371095744 14.337 100 23 44 0 1383649840 7937360 4616736
19:00:49 91538 8192000000 10280690832 10728271872 1346470960 14.116 98 23 46 1 1365473072 14118800 4883312
19:03:26 42897 8192000000 10300944032 10741272576 1360662512 14.243 99 22 44 1 1376730512 11108656 4959344
19:05:12 107073 8192000000 10297745568 10765107200 1382792416 14.442 100 7 14 0 1409493168 25120608 1580144
19:08:00 43163 8192000000 10308772928 10744745984 1381566896 14.431 99 21 42 0 1391358896 5206320 4585680
19:09:46 10598 8192000000 10314525952 10755895296 1397559824 14.573 100 46 90 0 1416536048 8865904 10110320
19:10:58 68963 8192000000 10291778224 10756780032 1378063536 14.399 98 13 26 0 1391228800 10068288 3096976
19:13:37 71515 8192000000 10302218528 10770427904 1377884944 14.398 98 11 22 0 1395694896 15056944 2753008
19:16:10 37466 8192000000 10303712912 10755088384 1362908144 14.263 97 38 76 0 1386203264 14153744 9141376
19:17:49 26602 8192000000 10286831184 10738573312 1368099680 14.310 98 21 40 0 1379649120 7214544 4334896
19:19:18 39777 8192000000 10295177744 10742165504 1355677376 14.199 97 17 36 0 1366780416 7081168 4021872
19:21:00 46946 8192000000 10290629872 10727481344 1335609808 14.018 96 25 50 0 1350215920 9221968 5384144
19:22:47 99658 8192000000 10281164656 10747211776 1347684064 14.127 97 22 44 0 1364578464 12072528 4821872
19:25:27 96860 8192000000 10282983216 10728304640 1322102528 13.896 96 20 40 0 1336047376 9275072 4669776
19:28:07 120992 8192000000 10273660224 10721259520 1326596688 13.936 96 22 44 1 1347858480 16925344 4336448
19:31:21 43962 8192000000 10275071008 10721738752 1366224112 14.293 100 35 70 1 1384766400 11016592 7525696
19:33:05 66201 8192000000 10263071808 10723737600 1321875584 13.894 96 9 18 0 1340164112 16286336 2002192
19:35:12 98475 8192000000 10246123936 10703691776 1310321888 13.789 96 17 34 1 1327674928 13231600 4121440
19:38:02 27195 8192000000 10264345840 10704875520 1320984576 13.886 97 20 39 1 1334143520 8977488 4181456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-10-2022 19:40:09 to 25-10-2022 19:40:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

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

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          242894
MON$OLDEST_ACTIVE               244309
MON$OLDEST_SNAPSHOT             244309
MON$NEXT_TRANSACTION            244309
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                       2021632
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.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 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 ---
:199: 
:200: mscs-test-161.budzdorov.ru    Tue Oct 25 19:38:35 2022
:201:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:202: 
:203: 
:204: mscs-test-161.budzdorov.ru    Tue Oct 25 19:38:38 2022
:205:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:206: 
:207: 
:208: mscs-test-161.budzdorov.ru    Tue Oct 25 19:38:40 2022
:209:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:210: 
:211: 
:212: mscs-test-161.budzdorov.ru    Tue Oct 25 19:38:40 2022
:213:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:214: 
--- end of diff output ---

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

25.10.22 19:40:29. 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-25 19:40:31 No severe PSQL-related problems occured
25.10.22 19:40:32. End of report.