Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.04.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 16761496 kB
MemAvailable 31515600 kB
Buffers 0 kB
Cached 14987144 kB
SwapCached 2712 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.10
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-04-19 19:38:11
DTS_END                         2022-04-19 19:38:11.5000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46892
TRN_ID                          235852
ATT_ID                          687
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: 9096.17 28323 1091540
customer order: creation 159.01 337 19081
customer order: refuse 13.28 342 1594
order to supplier: creation 42.84 1216 5141
order to supplier: removal 3.64 99037 437
invoice (draft): creation 43.86 3489 5263
invoice (draft): removal 52.84 791 6341
invoice accept: apply 40.35 18859 4842
invoice accept: cancel 47.92 769 5750
customer reserve: creation 3706.83 189 444820
customer reserve: removal 4704.31 60 564517
realization accept: apply 78.50 152 9420
realization accept: cancel 83.07 112 9968
payment from customer: creation 54.45 101 6534
payment from customer: removal 5.00 69 600
payment to supplier: creation 45.27 122 5432
payment to supplier: removal 3.13 57 376
service: total inventory turnovers 4.44 1165 533
service: total monetary turnovers 6.53 34 783
service: aggregate perf. data 0.87 14468 104
service: refresh index statistics 0.03 425089 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6315
TEST_TIME 2 6406
TEST_TIME 3 6427
TEST_TIME 4 6796
TEST_TIME 5 6491
TEST_TIME 6 6334
TEST_TIME 7 6642
TEST_TIME 8 6387
TEST_TIME 9 6264
TEST_TIME 10 6090
TEST_TIME 11 6044
TEST_TIME 12 6259
TEST_TIME 13 6230
TEST_TIME 14 6406
TEST_TIME 15 6529
TEST_TIME 16 6664
TEST_TIME 17 6782
TEST_TIME 18 6967
TEST_TIME 19 7429
TEST_TIME 20 7817
TEST_TIME 21 8071
TEST_TIME 22 8734
TEST_TIME 23 9429
TEST_TIME 24 10007
TEST_TIME 25 10735
TEST_TIME 26 10842
TEST_TIME 27 10483
TEST_TIME 28 10291
TEST_TIME 29 10159
TEST_TIME 30 10091
TEST_TIME 31 10328
TEST_TIME 32 10108
TEST_TIME 33 9951
TEST_TIME 34 9755
TEST_TIME 35 9772
TEST_TIME 36 9843
TEST_TIME 37 9672
TEST_TIME 38 9563
TEST_TIME 39 9679
TEST_TIME 40 9624
TEST_TIME 41 9571
TEST_TIME 42 9568
TEST_TIME 43 9637
TEST_TIME 44 9608
TEST_TIME 45 9544
TEST_TIME 46 9545
TEST_TIME 47 9538
TEST_TIME 48 9550
TEST_TIME 49 9634
TEST_TIME 50 9583
TEST_TIME 51 9490
TEST_TIME 52 9614
TEST_TIME 53 9720
TEST_TIME 54 9559
TEST_TIME 55 9496
TEST_TIME 56 9434
TEST_TIME 57 9381
TEST_TIME 58 9467
TEST_TIME 59 9562
TEST_TIME 60 9477
TEST_TIME 61 9494
TEST_TIME 62 9443
TEST_TIME 63 9422
TEST_TIME 64 9479
TEST_TIME 65 9434
TEST_TIME 66 9425
TEST_TIME 67 9494
TEST_TIME 68 9506
TEST_TIME 69 9478
TEST_TIME 70 9418
TEST_TIME 71 9400
TEST_TIME 72 9468
TEST_TIME 73 9500
TEST_TIME 74 9502
TEST_TIME 75 9475
TEST_TIME 76 9452
TEST_TIME 77 9459
TEST_TIME 78 9603
TEST_TIME 79 9629
TEST_TIME 80 9510
TEST_TIME 81 9497
TEST_TIME 82 9473
TEST_TIME 83 9417
TEST_TIME 84 9388
TEST_TIME 85 9341
TEST_TIME 86 9309
TEST_TIME 87 9250
TEST_TIME 88 9351
TEST_TIME 89 9314
TEST_TIME 90 9253
TEST_TIME 91 9214
TEST_TIME 92 9163
TEST_TIME 93 9155
TEST_TIME 94 9179
TEST_TIME 95 9231
TEST_TIME 96 9209
TEST_TIME 97 9237
TEST_TIME 98 9255
TEST_TIME 99 9238
TEST_TIME 100 9336
TEST_TIME 101 9317
TEST_TIME 102 9352
TEST_TIME 103 9384
TEST_TIME 104 9423
TEST_TIME 105 9425
TEST_TIME 106 9391
TEST_TIME 107 9397
TEST_TIME 108 9336
TEST_TIME 109 9308
TEST_TIME 110 9270
TEST_TIME 111 9290
TEST_TIME 112 9303
TEST_TIME 113 9264
TEST_TIME 114 9259
TEST_TIME 115 9205
TEST_TIME 116 9191
TEST_TIME 117 9159
TEST_TIME 118 9155
TEST_TIME 119 9174
TEST_TIME 120 9105

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-04-2022 19:39:46 to 19-04-2022 19:39:50.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1505247 1505247 0 0.00 0 17598 6 0 0
doc_list_biud 1505246 1505246 0 0.00 0 34436 28 0 0
sp_add_invoice_to_stock 4940 4842 98 1.98 27 192688 18860 0 98
sp_cancel_adding_invoice 5750 5750 0 0.00 5 66750 769 0 0
sp_cancel_client_order 1594 1594 0 0.00 4 11602 342 0 0
sp_cancel_customer_reserve 564517 564517 0 0.00 1 23597 61 0 0
sp_cancel_pay_from_customer 600 600 0 0.00 3 2144 70 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 2 1135 58 0 0
sp_cancel_supplier_invoice 6348 6341 7 0.11 1 34495 791 0 7
sp_cancel_supplier_order 437 437 0 0.00 5 1310872 99037 0 0
sp_cancel_write_off 9968 9968 0 0.00 1 11699 112 0 0
sp_client_order 19081 19081 0 0.00 4 30267 337 0 0
sp_customer_reserve 444820 444820 0 0.00 0 48010 190 0 0
sp_fill_shopping_cart 29460 29460 0 0.00 1 14575 40 0 0
sp_fill_shopping_cart_clo_res 444812 444812 0 0.00 0 12109 13 0 0
sp_get_clo_for_invoice 4830 4830 0 0.00 0 25338 296 0 0
sp_kill_cost_storno 16694 16694 0 0.00 0 13180 56 0 0
sp_kill_qstorno_ret_qs2qd 1145780 1145780 0 0.00 0 34433 33 0 0
sp_kill_qty_storno 602857 602857 0 0.00 0 34435 70 0 0
sp_lock_selected_doc 19701 19701 0 0.00 0 6033 4 0 0
sp_make_cost_storno 26216 26216 0 0.00 0 17596 51 0 0
sp_multiply_rows_for_pdistr 26216 26216 0 0.00 0 7587 26 0 0
sp_multiply_rows_for_qdistr 19081 19081 0 0.00 0 20464 151 0 0
sp_pay_from_customer 6534 6534 0 0.00 4 16524 102 0 0
sp_pay_to_supplier 5432 5432 0 0.00 4 8220 122 0 0
sp_payment_common 11966 11966 0 0.00 2 6113 23 0 0
sp_qd_handle_on_cancel_clo 1594 1594 0 0.00 0 11595 326 0 0
sp_qd_handle_on_invoice_upd_sts 10580 10580 0 0.00 0 32353 303 0 0
sp_qd_handle_on_reserve_upd_sts 9420 9420 0 0.00 0 16490 52 0 0
sp_reserve_write_off 9420 9420 0 0.00 6 16549 152 0 0
sp_supplier_invoice 5263 5263 0 0.00 5 86260 3490 0 0
sp_supplier_order 5141 5141 0 0.00 4 49532 1217 0 0
srv_aggregate_perf_data 104 104 0 0.00 2706 103060 14469 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2064 173282 46214 0 0
srv_find_qd_qs_mism 1047596 1047596 0 0.00 0 17465 6 0 0
srv_make_invnt_saldo 533 533 0 0.00 31 43795 1165 0 0
srv_make_money_saldo 783 783 0 0.00 1 3787 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 166033 636021 425089 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4842 4842 0 0.00 0 16972 176 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 9115 106 0 0
t$perf-norm:sp_cancel_client_order 1594 1594 0 0.00 0 18665 30 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 3137 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 600 600 0 0.00 0 324 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 4853 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 1707 24 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 23624 1455 0 0
t$perf-norm:sp_cancel_write_off 950 950 0 0.00 0 5501 17 0 0
t$perf-norm:sp_client_order 19081 19081 0 0.00 0 13823 22 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 4854 9 0 0
t$perf-norm:sp_pay_from_customer 6534 6534 0 0.00 0 8231 15 0 0
t$perf-norm:sp_pay_to_supplier 5432 5432 0 0.00 0 4382 10 0 0
t$perf-norm:sp_reserve_write_off 9420 9420 0 0.00 0 7044 11 0 0
t$perf-norm:sp_supplier_invoice 5263 5263 0 0.00 0 7261 22 0 0
t$perf-norm:sp_supplier_order 5141 5141 0 0.00 0 6272 19 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 499 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 364 8 0 0
t$perf-norm:srv_make_invnt_saldo 533 533 0 0.00 0 1165 16 0 0
t$perf-norm:srv_make_money_saldo 783 783 0 0.00 0 760 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 9856 9660 196 1.98 0 3172 15 0 196
v_all_customers 34982 34982 0 0.00 0 38 0 0 0
v_all_suppliers 28590 28590 0 0.00 0 20 1 0 0
v_all_wares 38162 38162 0 0.00 0 123 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 498 6 0 0
v_cancel_client_order 3188 3188 0 0.00 0 997 3 0 0
v_cancel_customer_prepayment 1200 1200 0 0.00 0 639 4 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 1687 4 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 202 2 0 0
v_cancel_supplier_invoice 804 790 14 1.74 0 2202 18 0 14
v_cancel_supplier_order 874 874 0 0.00 0 464 3 0 0
v_cancel_write_off 1900 1900 0 0.00 0 1899 12 0 0
v_max_id_clo_ord 5140 5140 0 0.00 0 1676 1 0 0
v_max_id_clo_res 934 934 0 0.00 0 1479 5 0 0
v_max_id_ord_sup 5233 5233 0 0.00 0 2938 3 0 0
v_max_non_paid_invoice 1510 1510 0 0.00 0 2020 4 0 0
v_max_non_paid_realizn 3411 3411 0 0.00 0 1850 5 0 0
v_min_id_clo_ord 5140 5140 0 0.00 0 3321 8 0 0
v_min_id_clo_res 934 934 0 0.00 0 3472 10 0 0
v_min_id_ord_sup 5233 5233 0 0.00 0 11729 14 0 0
v_min_non_paid_invoice 5432 5432 0 0.00 0 4348 13 0 0
v_min_non_paid_realizn 6534 6534 0 0.00 0 6110 19 0 0
v_our_firm 9426 9426 0 0.00 0 15 1 0 0
v_reserve_write_off 18840 18840 0 0.00 0 3231 5 0 0
x_lock_depdocs_on_canc_invoice 11696 11696 0 0.00 0 10908 122 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 4993 306 0 0
x_make_qstorno_1000_1200 5141 5141 0 0.00 1 49523 1141 0 0
x_make_qstorno_1200_2000 5263 5263 0 0.00 2 85066 3340 0 0
x_make_qstorno_2100_3300 444820 444820 0 0.00 0 43707 174 0 0

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

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:15 93 8192000000 8831144128 8852119552 21542640 0.262 4 4 5 0 21770848 88896 139312
17:09:15 31756 8192000000 9904948272 10213986304 985683744 10.740 100 82 164 0 1013728432 6558112 21486576
17:10:50 37958 8192000000 9952177392 10275430400 1035091600 11.217 100 89 178 0 1066714912 8827600 22795712
17:12:31 15364 8192000000 9988394128 10323787776 1070674608 11.559 100 82 164 0 1100973792 10144800 20154384
17:13:51 9031 8192000000 10047528720 10392973312 1126550320 12.089 100 76 152 0 1154863152 10639648 17673184
17:15:05 8258 8192000000 10099777136 10462879744 1188744048 12.672 100 87 174 1 1222224112 13700176 19779888
17:16:15 5558 8192000000 10142436496 10516180992 1231553744 13.068 100 77 151 2 1265205520 17504640 16147136
17:17:23 8074 8192000000 10175993056 10558042112 1271019312 13.431 100 76 152 0 1301098400 13886544 16192544
17:18:32 1482 8192000000 10214828976 10597511168 1296851488 13.667 100 89 177 0 1329728528 12922336 19954704
17:19:35 7853 8192000000 10208658736 10603773952 1305326928 13.744 100 73 148 1 1330335008 9176688 15831392
17:20:44 5596 8192000000 10193779472 10609364992 1307411824 13.763 100 22 45 0 1318886320 6685264 4789232
17:21:49 12519 8192000000 10200924192 10619142144 1315448080 13.835 100 17 34 0 1325713008 6065968 4198960
17:23:02 25628 8192000000 10216486384 10638528512 1325999216 13.931 100 29 58 1 1339428624 6555696 6873712
17:24:55 8468 8192000000 10228135712 10648612864 1330492480 13.972 100 31 61 1 1348297776 10605344 7199952
17:26:04 10533 8192000000 10219764944 10637737984 1315768048 13.838 99 27 54 0 1328300832 6605904 5926880
17:27:15 13622 8192000000 10178018816 10588143616 1255546496 13.289 97 9 18 1 1262731808 5247584 1937728
17:28:31 34910 8192000000 10158955552 10568642560 1207747968 12.848 94 28 56 1 1220313952 6114400 6451584
17:30:09 5880 8192000000 10156642336 10557448192 1236911648 13.118 98 31 62 1 1251698208 6806240 7980320
17:31:16 5279 8192000000 10143077072 10543587328 1228427056 13.040 98 33 66 1 1242168048 5423136 8317856
17:32:25 51139 8192000000 10175411600 10572201984 1268637776 13.409 100 43 86 2 1284811136 6355744 9817616
17:34:18 7782 8192000000 10172574784 10584158208 1283437776 13.544 100 26 52 0 1295360896 5416864 6506256
17:35:26 5715 8192000000 10194960128 10608816128 1305180288 13.742 100 33 66 2 1319120768 6673088 7267392
17:36:33 9424 8192000000 10204526960 10625351680 1316563168 13.846 100 20 40 2 1326651664 5343088 4745408
17:37:43 98306 8192000000 10243969200 10660122624 1339201872 14.050 100 40 77 1 1356509152 7314928 9992352
17:40:26 5744 8192000000 10261471504 10671849472 1352346032 14.169 99 68 134 2 1380110944 12829856 14935056
17:41:40 15623 8192000000 10300802528 10717126656 1369000128 14.318 99 54 107 2 1402971136 21654576 12316432
17:42:59 12374 8192000000 10316658144 10737020928 1395997168 14.559 100 50 98 2 1425940192 18530480 11412544
17:44:25 13074 8192000000 10326959744 10746015744 1400460000 14.599 100 50 99 0 1430383120 17828544 12094576
17:45:45 5515 8192000000 10320048112 10742046720 1397764592 14.575 100 61 119 1 1432656416 20927200 13964624
17:46:56 14320 8192000000 10316508960 10735382528 1367124640 14.301 97 47 92 1 1399878032 21656272 11097120
17:48:16 9658 8192000000 10325806192 10739892224 1392530032 14.528 100 69 136 1 1432015792 23292912 16192848
17:49:31 6561 8192000000 10337534448 10753679360 1398963856 14.586 100 58 115 3 1445799440 32041840 14793744
17:50:42 7320 8192000000 10336523904 10749591552 1394833920 14.549 99 71 139 2 1445785440 32880400 18071120
17:51:59 6206 8192000000 10351546608 10764054528 1400264512 14.597 100 71 142 1 1457458400 38908720 18285168
17:53:09 2063 8192000000 10353407584 10771591168 1409049088 14.675 100 62 124 0 1465862928 40373232 16440608
17:54:22 2569 8192000000 10370149136 10775003136 1404738480 14.637 100 83 166 0 1469856448 43727808 21390160
17:55:30 4049 8192000000 10370497824 10779770880 1395232912 14.553 99 81 162 2 1463786400 48070992 20482496
17:56:43 2416 8192000000 10387982016 10795954176 1410541872 14.689 100 87 174 2 1490812896 57059168 23211856
17:57:53 9281 8192000000 10355418384 10781532160 1386435120 14.474 99 56 112 1 1458084496 56613824 15035552
17:59:13 4803 8192000000 10351132240 10776559616 1390322688 14.509 100 49 98 0 1459364592 55683216 13358688
18:00:33 31378 8192000000 10306688176 10762194944 1354294368 14.186 98 29 58 1 1409719744 47129552 8295824
18:02:26 32454 8192000000 10283529760 10726309888 1329972704 13.967 98 19 38 1 1371658448 36172816 5512928
18:04:00 38143 8192000000 10270187248 10706817024 1340014704 14.058 98 32 62 3 1358598352 11142816 7440832
18:05:38 59949 8192000000 10282146240 10721837056 1364767264 14.280 100 41 82 0 1383883664 9408304 9708096
18:07:40 86683 8192000000 10276943104 10729594880 1362515312 14.260 99 29 58 1 1385001984 15999168 6487504
18:10:12 23456 8192000000 10275610592 10734862336 1376035664 14.381 100 9 19 0 1393393216 15595440 1762112
18:11:36 41299 8192000000 10273303072 10723180544 1375450112 14.376 100 16 32 0 1387917568 9068208 3399248
18:13:18 39954 8192000000 10283935296 10740441088 1380920496 14.425 100 14 28 0 1400092080 15595136 3576448
18:15:04 48399 8192000000 10275494624 10729930752 1365013936 14.282 99 17 34 0 1377299984 8190144 4095904
18:17:27 63012 8192000000 10273395872 10742431744 1342582944 14.081 97 5 10 0 1356309856 12931696 795216
18:19:31 43760 8192000000 10307763440 10767773696 1397622352 14.574 100 23 46 0 1420271024 17092480 5556192
18:21:15 81913 8192000000 10314239904 10755801088 1380451504 14.421 98 31 62 0 1401438928 14455072 6532352
18:23:39 28245 8192000000 10313762336 10763247616 1394458448 14.546 99 31 62 4 1409238288 7216352 7563488
18:25:12 43703 8192000000 10321406128 10770030592 1403614304 14.627 100 31 61 1 1422368016 11867552 6886160
18:27:05 73725 8192000000 10312808848 10765250560 1388758528 14.495 99 18 36 0 1407844736 14681680 4404528
18:29:24 42402 8192000000 10332432112 10785935360 1406262192 14.651 99 27 54 0 1427613616 15214176 6137248
18:31:12 117529 8192000000 10313654496 10757414912 1385385200 14.465 98 14 28 1 1404256368 15674512 3196656
18:34:14 90844 8192000000 10323993872 10790916096 1406546144 14.653 100 15 30 2 1430862272 20602256 3713872
18:36:46 42053 8192000000 10295656880 10754510848 1382997184 14.443 99 12 24 0 1407931136 21787728 3146224
18:38:28 41703 8192000000 10281267328 10741026816 1350451600 14.152 97 10 20 0 1372734224 20324752 1957872
18:40:10 59693 8192000000 10288263712 10756231168 1376588688 14.386 99 11 22 2 1395831248 16660752 2581808
18:42:31 74713 8192000000 10270815760 10728431616 1322866496 13.903 95 10 20 0 1332764976 7906384 1992096
18:44:52 52153 8192000000 10279183344 10732158976 1356574944 14.207 99 15 30 0 1369273136 9651968 3046224
18:46:45 45335 8192000000 10230750064 10678923264 1257224032 13.305 92 10 20 0 1267109568 7820960 2064576
18:48:37 12073 8192000000 10290623872 10715955200 1363691696 14.270 100 60 116 1 1387120832 11275504 12153632
18:49:53 57014 8192000000 10283429072 10737102848 1377122704 14.391 100 37 74 0 1401987952 16913552 7951696
18:51:51 96303 8192000000 10276970240 10724564992 1315060960 13.832 96 18 36 0 1329591536 10909760 3620816
18:54:27 27640 8192000000 10299562912 10733539328 1376280432 14.383 100 42 84 1 1396412512 11039472 9092608
18:55:57 45682 8192000000 10292281232 10757316608 1362869712 14.263 99 15 30 0 1387434912 21103504 3461696
18:57:45 173282 8192000000 10276163648 10732408832 1312139536 13.805 95 19 38 1 1337454672 21335200 3979936
19:01:39 60356 8192000000 10260788000 10718588928 1362544048 14.260 100 21 42 1 1381078512 13809216 4725248
19:03:39 59827 8192000000 10262496816 10710810624 1317903056 13.858 96 15 30 0 1334837456 13373696 3560704
19:05:54 73456 8192000000 10251940448 10710925312 1349973648 14.147 100 13 26 2 1372223504 19119344 3130512
19:08:45 50648 8192000000 10242453888 10696851456 1324270448 13.915 97 21 42 0 1334716640 6391488 4054704
19:11:01 89218 8192000000 10272436192 10742079488 1361800128 14.254 99 8 16 0 1373568240 10062848 1705264
19:13:30 44175 8192000000 10292039248 10740948992 1380571968 14.422 100 15 30 0 1391663456 8371232 2720256
19:15:16 38319 8192000000 10302559056 10763255808 1395253104 14.553 100 38 76 1 1419344768 15781488 8310176
19:17:27 62791 8192000000 10298208816 10748088320 1349339760 14.142 97 28 56 2 1372907536 16687248 6880528
19:19:59 68384 8192000000 10296106896 10754207744 1372099024 14.346 99 19 38 0 1392291776 16017232 4175520
19:23:25 82920 8192000000 10313498736 10762391552 1366639824 14.297 98 13 26 0 1383729568 14328704 2761040
19:25:49 77207 8192000000 10289701088 10744352768 1376653696 14.387 99 12 24 0 1396936032 17187296 3095040
19:28:07 46686 8192000000 10292428320 10737610752 1368691152 14.315 99 42 84 0 1396681024 18878928 9110944
19:30:28 61968 8192000000 10267215136 10731548672 1352899968 14.174 98 4 8 0 1363665440 10006272 759200
19:32:30 52678 8192000000 10258592496 10714181632 1325260576 13.924 96 15 30 1 1339005424 10389440 3355408
19:34:24 55363 8192000000 10278122208 10733977600 1363238496 14.266 100 16 32 0 1382107728 15635632 3233600
19:36:21 53001 8192000000 10278900672 10727911424 1332443824 13.989 97 19 38 0 1355126192 18239072 4443296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          230962
MON$OLDEST_ACTIVE               235994
MON$OLDEST_SNAPSHOT             235994
MON$NEXT_TRANSACTION            235994
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                       2015360
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:667: 
:668: mscs-test-161.budzdorov.ru    Tue Apr 19 19:38:18 2022
:669:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:670: 
:671: 
:672: mscs-test-161.budzdorov.ru    Tue Apr 19 19:38:19 2022
:673:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:674: 
--- end of diff output ---

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

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