Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.01.2023 22: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 15584956 kB
MemAvailable 30385988 kB
Buffers 0 kB
Cached 16115084 kB
SwapCached 4424 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-12 22:38:33
DTS_END                         2023-01-12 22:38:33.3870
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/46274
TRN_ID                          237276
ATT_ID                          644
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: 9038.92 27349 1084670
customer order: creation 167.66 387 20119
customer order: refuse 14.88 335 1785
order to supplier: creation 44.38 1322 5326
order to supplier: removal 3.25 105800 390
invoice (draft): creation 45.18 3320 5421
invoice (draft): removal 50.79 759 6095
invoice accept: apply 40.95 16872 4914
invoice accept: cancel 45.90 701 5508
customer reserve: creation 3820.78 165 458493
customer reserve: removal 4524.18 60 542902
realization accept: apply 80.82 197 9698
realization accept: cancel 73.62 119 8834
payment from customer: creation 57.86 132 6943
payment from customer: removal 5.59 137 671
payment to supplier: creation 47.59 136 5711
payment to supplier: removal 3.59 134 431
service: total inventory turnovers 4.78 960 573
service: total monetary turnovers 6.35 25 762
service: aggregate perf. data 0.75 24865 90
service: refresh index statistics 0.03 390558 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-01-2023 22:39:45 to 12-01-2023 22:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2950
TEST_TIME 2 4148
TEST_TIME 3 3643
TEST_TIME 4 3597
TEST_TIME 5 4135
TEST_TIME 6 4189
TEST_TIME 7 4590
TEST_TIME 8 5229
TEST_TIME 9 5569
TEST_TIME 10 5376
TEST_TIME 11 5348
TEST_TIME 12 5273
TEST_TIME 13 5295
TEST_TIME 14 5730
TEST_TIME 15 5981
TEST_TIME 16 6245
TEST_TIME 17 6521
TEST_TIME 18 7062
TEST_TIME 19 7672
TEST_TIME 20 7782
TEST_TIME 21 8225
TEST_TIME 22 8505
TEST_TIME 23 8683
TEST_TIME 24 9200
TEST_TIME 25 9631
TEST_TIME 26 9887
TEST_TIME 27 10661
TEST_TIME 28 10617
TEST_TIME 29 10454
TEST_TIME 30 10329
TEST_TIME 31 10177
TEST_TIME 32 10293
TEST_TIME 33 10107
TEST_TIME 34 9919
TEST_TIME 35 9953
TEST_TIME 36 9824
TEST_TIME 37 9764
TEST_TIME 38 9761
TEST_TIME 39 9615
TEST_TIME 40 9501
TEST_TIME 41 9367
TEST_TIME 42 9518
TEST_TIME 43 9490
TEST_TIME 44 9421
TEST_TIME 45 9403
TEST_TIME 46 9380
TEST_TIME 47 9328
TEST_TIME 48 9325
TEST_TIME 49 9214
TEST_TIME 50 9329
TEST_TIME 51 9282
TEST_TIME 52 9313
TEST_TIME 53 9303
TEST_TIME 54 9380
TEST_TIME 55 9329
TEST_TIME 56 9427
TEST_TIME 57 9451
TEST_TIME 58 9461
TEST_TIME 59 9399
TEST_TIME 60 9440
TEST_TIME 61 9586
TEST_TIME 62 9545
TEST_TIME 63 9554
TEST_TIME 64 9431
TEST_TIME 65 9335
TEST_TIME 66 9350
TEST_TIME 67 9269
TEST_TIME 68 9185
TEST_TIME 69 9233
TEST_TIME 70 9155
TEST_TIME 71 9184
TEST_TIME 72 9129
TEST_TIME 73 9046
TEST_TIME 74 9012
TEST_TIME 75 8969
TEST_TIME 76 8957
TEST_TIME 77 8905
TEST_TIME 78 8979
TEST_TIME 79 8978
TEST_TIME 80 8958
TEST_TIME 81 9027
TEST_TIME 82 8985
TEST_TIME 83 8984
TEST_TIME 84 9094
TEST_TIME 85 9143
TEST_TIME 86 9204
TEST_TIME 87 9197
TEST_TIME 88 9239
TEST_TIME 89 9250
TEST_TIME 90 9191
TEST_TIME 91 9117
TEST_TIME 92 9099
TEST_TIME 93 9097
TEST_TIME 94 9106
TEST_TIME 95 9041
TEST_TIME 96 9014
TEST_TIME 97 9070
TEST_TIME 98 9061
TEST_TIME 99 9109
TEST_TIME 100 9168
TEST_TIME 101 9134
TEST_TIME 102 9140
TEST_TIME 103 9185
TEST_TIME 104 9214
TEST_TIME 105 9230
TEST_TIME 106 9264
TEST_TIME 107 9251
TEST_TIME 108 9240
TEST_TIME 109 9212
TEST_TIME 110 9198
TEST_TIME 111 9158
TEST_TIME 112 9110
TEST_TIME 113 9111
TEST_TIME 114 9125
TEST_TIME 115 9079
TEST_TIME 116 9047
TEST_TIME 117 9061
TEST_TIME 118 9068
TEST_TIME 119 9054
TEST_TIME 120 9040

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-01-2023 22:39:49 to 12-01-2023 22:39:52.

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 1507877 1507877 0 0.00 0 32895 6 0 0
doc_list_biud 1507877 1507877 0 0.00 0 34067 27 0 0
sp_add_invoice_to_stock 5023 4914 109 2.17 49 216496 16872 0 109
sp_cancel_adding_invoice 5508 5508 0 0.00 4 71194 702 0 0
sp_cancel_client_order 1785 1785 0 0.00 4 8662 335 0 0
sp_cancel_customer_reserve 542902 542902 0 0.00 1 34071 61 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 10131 137 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 10279 135 0 0
sp_cancel_supplier_invoice 6106 6095 11 0.18 1 15384 760 0 11
sp_cancel_supplier_order 390 390 0 0.00 9 1072790 105800 0 0
sp_cancel_write_off 8834 8834 0 0.00 1 18046 119 0 0
sp_client_order 20119 20119 0 0.00 3 39909 388 0 0
sp_customer_reserve 458493 458493 0 0.00 0 64973 165 0 0
sp_fill_shopping_cart 30862 30862 0 0.00 1 11193 36 0 0
sp_fill_shopping_cart_clo_res 458491 458491 0 0.00 0 22510 17 0 0
sp_get_clo_for_invoice 4912 4912 0 0.00 0 27205 314 0 0
sp_kill_cost_storno 15444 15444 0 0.00 0 10272 57 0 0
sp_kill_qstorno_ret_qs2qd 1101123 1101123 0 0.00 0 34061 33 0 0
sp_kill_qty_storno 580124 580124 0 0.00 0 34067 70 0 0
sp_lock_selected_doc 20334 20334 0 0.00 0 9625 7 0 0
sp_make_cost_storno 27264 27264 0 0.00 0 13575 57 0 0
sp_multiply_rows_for_pdistr 27264 27264 0 0.00 0 6891 26 0 0
sp_multiply_rows_for_qdistr 20119 20119 0 0.00 0 22398 162 0 0
sp_pay_from_customer 6943 6943 0 0.00 4 19525 133 0 0
sp_pay_to_supplier 5711 5711 0 0.00 5 18026 136 0 0
sp_payment_common 12654 12654 0 0.00 2 12873 33 0 0
sp_qd_handle_on_cancel_clo 1785 1785 0 0.00 0 7410 290 0 0
sp_qd_handle_on_invoice_upd_sts 10420 10420 0 0.00 0 20935 297 0 0
sp_qd_handle_on_reserve_upd_sts 9698 9698 0 0.00 0 16383 64 0 0
sp_reserve_write_off 9698 9698 0 0.00 6 20503 198 0 0
sp_supplier_invoice 5421 5421 0 0.00 6 85530 3321 0 0
sp_supplier_order 5326 5326 0 0.00 5 44812 1323 0 0
srv_aggregate_perf_data 90 90 0 0.00 2731 309544 24865 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 997 104660 43490 0 0
srv_find_qd_qs_mism 1037125 1037125 0 0.00 0 32893 5 0 0
srv_make_invnt_saldo 573 573 0 0.00 26 49515 961 0 0
srv_make_money_saldo 762 762 0 0.00 1 5237 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 117621 569372 390558 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 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 4914 4914 0 0.00 0 20023 183 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 2366 85 0 0
t$perf-norm:sp_cancel_client_order 1785 1785 0 0.00 0 1770 11 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 8713 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 1609 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 98 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 1619 13 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 27908 1631 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 2743 8 0 0
t$perf-norm:sp_client_order 20119 20119 0 0.00 0 7420 19 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 6017 15 0 0
t$perf-norm:sp_pay_from_customer 6943 6943 0 0.00 0 15885 13 0 0
t$perf-norm:sp_pay_to_supplier 5711 5711 0 0.00 0 16670 19 0 0
t$perf-norm:sp_reserve_write_off 9698 9698 0 0.00 0 10557 14 0 0
t$perf-norm:sp_supplier_invoice 5421 5421 0 0.00 0 11501 20 0 0
t$perf-norm:sp_supplier_order 5326 5326 0 0.00 0 6228 14 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 1481 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 368 11 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 7610 29 0 0
t$perf-norm:srv_make_money_saldo 762 762 0 0.00 0 1493 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 5 0 0
v_add_invoice_to_stock 10038 9820 218 2.17 0 27911 26 0 218
v_all_customers 36246 36246 0 0.00 0 234 1 0 0
v_all_suppliers 29588 29588 0 0.00 0 22 1 0 0
v_all_wares 40238 40238 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 932 8 0 0
v_cancel_client_order 3570 3570 0 0.00 0 8400 10 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 2264 9 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 532 4 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 5083 17 0 0
v_cancel_supplier_invoice 756 734 22 2.91 0 376 12 0 22
v_cancel_supplier_order 780 780 0 0.00 0 1972 9 0 0
v_cancel_write_off 1960 1960 0 0.00 0 18037 24 0 0
v_max_id_clo_ord 5322 5322 0 0.00 0 269 1 0 0
v_max_id_clo_res 984 984 0 0.00 0 4513 18 0 0
v_max_id_ord_sup 5420 5420 0 0.00 0 3211 4 0 0
v_max_non_paid_invoice 1659 1659 0 0.00 0 2607 4 0 0
v_max_non_paid_realizn 3750 3750 0 0.00 0 5565 9 0 0
v_min_id_clo_ord 5322 5322 0 0.00 0 3947 6 0 0
v_min_id_clo_res 984 984 0 0.00 0 3754 14 0 0
v_min_id_ord_sup 5420 5420 0 0.00 0 5526 10 0 0
v_min_non_paid_invoice 5711 5711 0 0.00 0 5316 16 0 0
v_min_non_paid_realizn 6943 6943 0 0.00 0 12866 25 0 0
v_our_firm 10378 10378 0 0.00 0 15 1 0 0
v_reserve_write_off 19396 19396 0 0.00 0 20476 12 0 0
x_lock_depdocs_on_canc_invoice 11236 11236 0 0.00 0 13889 121 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 22668 410 0 0
x_make_qstorno_1000_1200 5326 5326 0 0.00 1 44802 1254 0 0
x_make_qstorno_1200_2000 5421 5421 0 0.00 3 85165 3182 0 0
x_make_qstorno_2100_3300 458493 458493 0 0.00 0 64945 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-01-2023 22:39:53 to 12-01-2023 22:39:57.

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
20:08:37 2 8192000000 8797916768 8808923136 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:38 29269 8192000000 9901568512 10209247232 982778240 10.711 100 89 178 0 1012383584 6161136 23444208
20:11:12 14548 8192000000 9943757824 10264371200 1026757568 11.137 100 86 172 0 1056656960 7958112 21941280
20:12:36 14302 8192000000 9989196864 10321186816 1074616480 11.596 100 83 166 0 1103983696 8491824 20875392
20:13:55 9667 8192000000 10050347600 10396983296 1132769568 12.147 100 81 162 0 1162137168 9953648 19413952
20:15:10 5920 8192000000 10082572992 10441445376 1171217424 12.508 100 73 146 1 1200184592 11635760 17331408
20:16:20 4463 8192000000 10128263264 10492334080 1213151632 12.898 100 86 168 1 1244675088 13236896 18286560
20:17:26 3183 8192000000 10150394080 10526117888 1242961152 13.173 100 83 164 0 1272852304 12211392 17679760
20:18:31 1594 8192000000 10184398272 10564472832 1270250752 13.424 100 88 173 0 1303556080 14133792 19171536
20:19:35 1256 8192000000 10199083792 10580074496 1286841376 13.575 100 82 160 0 1316669136 12577984 17249776
20:20:39 1216 8192000000 10214537744 10606137344 1299766144 13.693 100 78 156 1 1333211280 15591488 17853648
20:21:42 19598 8192000000 10199611920 10602008576 1307764784 13.766 100 51 100 0 1325098160 5521552 11811824
20:23:02 7546 8192000000 10196083520 10616541184 1316155680 13.842 100 25 50 0 1327743024 5905920 5681424
20:24:13 7204 8192000000 10210958864 10630549504 1321728352 13.892 100 21 42 0 1336833504 10606224 4498928
20:25:20 27501 8192000000 10235991952 10638647296 1325380576 13.925 100 62 121 3 1345753584 6241632 14131376
20:26:48 5677 8192000000 10212903472 10631421952 1325314112 13.925 100 25 51 0 1336566160 5349168 5902880
20:27:54 7903 8192000000 10220650720 10639589376 1329834000 13.966 100 24 48 0 1341218304 5933072 5451232
20:29:03 8431 8192000000 10191162976 10603655168 1301115632 13.705 99 30 60 0 1313390976 5710192 6565152
20:30:13 27717 8192000000 10179523920 10590736384 1227593552 13.032 94 21 40 2 1238406768 5521024 5292192
20:31:43 7601 8192000000 10145412512 10545491968 1205368000 12.826 95 24 49 1 1217138912 6274656 5496256
20:32:56 6376 8192000000 10156519200 10554040320 1260410032 13.334 100 31 62 1 1273492096 5248560 7833504
20:34:06 68485 8192000000 10170090608 10574225408 1254329264 13.278 98 30 60 0 1266040896 5063072 6648560
20:36:18 7345 8192000000 10176569824 10591948800 1290240448 13.606 100 30 60 0 1303649248 6316624 7092176
20:37:26 9876 8192000000 10188438544 10602967040 1300079200 13.696 100 24 48 0 1311469520 5371632 6018688
20:38:38 4559 8192000000 10230022080 10636218368 1324973840 13.922 100 53 107 0 1342892512 5004672 12914000
20:39:50 57260 8192000000 10241293136 10656694272 1341664800 14.072 100 47 90 1 1362371072 9558880 11147392
20:41:56 44068 8192000000 10270559984 10690666496 1362015056 14.255 100 32 64 0 1383539264 13948688 7575520
20:43:51 11625 8192000000 10295290528 10715185152 1376108608 14.382 100 44 87 2 1402984560 16783024 10092928
20:45:07 7155 8192000000 10302894912 10725404672 1387509136 14.484 100 44 88 1 1418741888 19954112 11278640
20:46:19 15031 8192000000 10315679728 10745061376 1396825616 14.567 100 51 100 1 1433523616 24376480 12321520
20:47:44 6273 8192000000 10340257328 10766118912 1414588208 14.725 100 50 100 0 1452727584 25830336 12309040
20:48:56 25144 8192000000 10332659376 10764587008 1403851264 14.629 99 37 74 0 1439905328 26356704 9697360
20:50:33 996 8192000000 10357616240 10778828800 1418771056 14.762 100 68 131 0 1464715632 29325712 16618864
20:51:37 7795 8192000000 10354295200 10776109056 1416850368 14.745 100 53 106 0 1464389664 33979904 13559392
20:52:50 1752 8192000000 10353629888 10764599296 1407279824 14.660 100 77 153 0 1462303360 36275776 18747760
20:53:58 9787 8192000000 10361914288 10780688384 1392686224 14.530 98 53 106 0 1451185376 43935456 14563696
20:55:16 5107 8192000000 10342860208 10763251712 1373848464 14.362 98 48 94 1 1434146880 47024976 13273440
20:56:29 3552 8192000000 10359735760 10771247104 1392954768 14.532 100 81 162 0 1465117024 51497232 20665024
20:57:39 2027 8192000000 10342296352 10756177920 1382839520 14.442 100 82 164 0 1452318688 48631664 20847504
20:58:44 8973 8192000000 10338381152 10758955008 1383951456 14.452 100 45 88 0 1443479136 47419296 12108384
20:59:56 11955 8192000000 10320020672 10749919232 1370273760 14.329 99 35 66 2 1424734704 44998992 9461952
21:01:11 22026 8192000000 10324447792 10761838592 1381481024 14.430 100 27 54 0 1433892272 44676224 7735024
21:02:38 20972 8192000000 10307720272 10749943808 1366548944 14.296 100 22 44 0 1417548064 44780016 6219104
21:04:07 32379 8192000000 10294016336 10737958912 1352840048 14.173 99 16 32 1 1398620560 41164416 4616096
21:05:49 33166 8192000000 10264755808 10699837440 1334916512 14.012 98 33 62 0 1350708352 8213552 7578288
21:07:24 45240 8192000000 10251593872 10697854976 1348745024 14.136 100 22 43 0 1360315888 7301120 4269744
21:09:13 56966 8192000000 10250784096 10681643008 1303257184 13.725 96 19 40 0 1317448544 9912240 4279120
21:11:18 97674 8192000000 10271824832 10711375872 1354883168 14.191 100 28 54 0 1371324560 9892832 6548560
21:14:35 66086 8192000000 10271032656 10709700608 1358433136 14.223 99 25 50 0 1375584832 11752848 5398848
21:16:49 80875 8192000000 10304456272 10738368512 1382753424 14.441 100 25 48 1 1397130512 8505184 5871904
21:19:14 54900 8192000000 10307917072 10755485696 1401252352 14.606 100 27 54 0 1423434352 15177904 7004096
21:21:09 54147 8192000000 10295511568 10763177984 1396392448 14.563 100 6 12 1 1413658272 16070032 1195792
21:23:09 59562 8192000000 10296847536 10760798208 1388949120 14.496 99 14 26 0 1408366192 16134496 3282576
21:25:09 30301 8192000000 10307921600 10766233600 1387318992 14.482 99 18 37 0 1409370480 17986048 4065440
21:26:43 38687 8192000000 10292260608 10758705152 1393359024 14.536 100 9 17 0 1406924784 11830480 1735280
21:28:27 74148 8192000000 10309508144 10750038016 1383123088 14.444 99 29 52 4 1401528832 12182608 6223136
21:30:41 71888 8192000000 10301636256 10753847296 1373203376 14.356 98 20 40 0 1388611216 10619872 4787968
21:32:54 37832 8192000000 10297218864 10755137536 1387175744 14.481 99 10 21 0 1402504016 12886736 2441536
21:34:34 29281 8192000000 10302915024 10753646592 1382799888 14.442 99 27 54 2 1397648832 9180016 5668928
21:36:04 41083 8192000000 10299799264 10750169088 1369440288 14.322 98 19 38 0 1389167616 15260912 4466416
21:37:54 94895 8192000000 10275403952 10736603136 1327257712 13.942 95 20 40 0 1356090784 24218112 4614960
21:40:29 41327 8192000000 10292426416 10736939008 1357769856 14.217 98 40 80 0 1383411632 16884816 8756960
21:42:11 84712 8192000000 10278115648 10716815360 1343772128 14.091 98 39 78 0 1360561952 7997024 8792800
21:44:44 56269 8192000000 10268451712 10727198720 1356886832 14.209 98 16 32 0 1370393392 10250656 3255904
21:46:57 42762 8192000000 10257128960 10719780864 1362479984 14.260 100 11 22 0 1381451456 16244880 2726592
21:48:41 47424 8192000000 10278478112 10723766272 1364452224 14.277 99 27 53 2 1382581632 12003728 6125680
21:50:32 73759 8192000000 10241491504 10690600960 1321035024 13.886 96 19 38 3 1332878176 7799040 4044112
21:52:47 54748 8192000000 10254561472 10701750272 1329168768 13.960 97 30 58 0 1346513184 10050720 7293696
21:54:44 104660 8192000000 10280498016 10734907392 1355495456 14.197 99 11 22 0 1370160816 12065104 2600256
21:57:29 22683 8192000000 10265274160 10706100224 1351963808 14.165 99 19 38 0 1365208608 8926944 4317856
21:58:52 49464 8192000000 10257687776 10712305664 1342490320 14.080 98 18 36 0 1357108944 10740608 3878016
22:00:50 35153 8192000000 10271735952 10708836352 1315450832 13.835 97 34 68 0 1338523504 15461392 7611280
22:03:59 50744 8192000000 10271297936 10707279872 1340075280 14.058 99 22 44 0 1362058000 16980496 5002224
22:05:51 83910 8192000000 10256745760 10708623360 1308389936 13.771 96 14 29 0 1321146992 9760144 2996912
22:08:16 83722 8192000000 10280422400 10722873344 1371982160 14.345 100 34 68 0 1390562656 11376272 7204224
22:10:46 20419 8192000000 10282073136 10727596032 1365839136 14.290 99 32 62 0 1385624320 12784416 7000768
22:12:59 47509 8192000000 10285273728 10740129792 1384316096 14.455 100 6 12 0 1393397888 7966240 1115552
22:14:47 54337 8192000000 10291892432 10747277312 1381726464 14.432 99 15 30 0 1398338160 13227872 3383824
22:16:48 69874 8192000000 10286128128 10741276672 1382627344 14.440 100 16 32 0 1406571520 20136144 3808032
22:19:14 34916 8192000000 10306337264 10749235200 1377863744 14.397 99 30 59 0 1399368352 15339744 6164864
22:20:52 84197 8192000000 10288558384 10747179008 1348228752 14.132 97 18 36 0 1365851776 13558224 4064800
22:23:35 46092 8192000000 10305227872 10743107584 1365227424 14.284 98 12 24 0 1377360576 9082256 3050896
22:26:01 46638 8192000000 10294411120 10732638208 1357719648 14.217 98 19 39 2 1370594496 8801264 4073584
22:27:59 90814 8192000000 10267195856 10719772672 1367016208 14.300 99 21 42 0 1382646848 10959904 4670736
22:30:32 25314 8192000000 10297724464 10732544000 1366532224 14.296 99 34 67 1 1387343648 13552352 7259072
22:31:58 47264 8192000000 10268942336 10733457408 1374176672 14.364 100 4 8 0 1392284976 17183728 924576
22:33:45 57934 8192000000 10257633440 10702819328 1310947024 13.795 95 15 30 0 1323813264 9368864 3497376
22:35:43 65822 8192000000 10248301504 10697842688 1265747328 13.383 93 10 20 2 1280412048 12012240 2652480
22:37:57 39107 8192000000 9040769040 9281908736 198667440 2.367 15 4 8 0 201056256 1602336 786480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-01-2023 22:39:59 to 12-01-2023 22:40:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 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 12-01-2023 22:40:16 to 12-01-2023 22:40:18.

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          237667
MON$OLDEST_ACTIVE               237668
MON$OLDEST_SNAPSHOT             237668
MON$NEXT_TRANSACTION            237668
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                       1997888
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.33653 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33653 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33653 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 ---
:104: 
:105: mscs-test-161.budzdorov.ru    Thu Jan 12 22:38:37 2023
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:107: 
:108: 
:109: mscs-test-161.budzdorov.ru    Thu Jan 12 22:38:37 2023
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:111: 
:112: 
:113: mscs-test-161.budzdorov.ru    Thu Jan 12 22:38:37 2023
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:115: 
--- end of diff output ---

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

12.01.23 22:40:19. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-01-12 22:40:21 No severe PSQL-related problems occured
12.01.23 22:40:23. End of report.