Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.06.2022 19:38
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 16192576 kB
MemAvailable 31298584 kB
Buffers 0 kB
Cached 15496288 kB
SwapCached 2448 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-06-06 19:38:19
DTS_END                         2022-06-06 19:38:19.6790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43760
TRN_ID                          240953
ATT_ID                          652
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: 9274.78 20871 1112973
customer order: creation 169.75 390 20370
customer order: refuse 14.69 356 1763
order to supplier: creation 44.78 1272 5374
order to supplier: removal 3.55 104974 426
invoice (draft): creation 44.12 3675 5294
invoice (draft): removal 53.73 826 6448
invoice accept: apply 41.47 19298 4976
invoice accept: cancel 48.76 852 5851
customer reserve: creation 3807.00 190 456840
customer reserve: removal 4745.54 61 569465
realization accept: apply 83.38 214 10006
realization accept: cancel 88.56 134 10627
payment from customer: creation 59.44 148 7133
payment from customer: removal 5.71 86 685
payment to supplier: creation 48.93 153 5871
payment to supplier: removal 3.14 77 377
service: total inventory turnovers 4.58 1387 549
service: total monetary turnovers 6.80 49 816
service: aggregate perf. data 0.82 23890 98
service: refresh index statistics 0.03 259380 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-06-2022 19:39:22 to 06-06-2022 19:39:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6153
TEST_TIME 2 3438
TEST_TIME 3 3585
TEST_TIME 4 5162
TEST_TIME 5 5297
TEST_TIME 6 5292
TEST_TIME 7 5699
TEST_TIME 8 5634
TEST_TIME 9 5335
TEST_TIME 10 5336
TEST_TIME 11 5760
TEST_TIME 12 5961
TEST_TIME 13 6167
TEST_TIME 14 6463
TEST_TIME 15 6953
TEST_TIME 16 7061
TEST_TIME 17 7316
TEST_TIME 18 7650
TEST_TIME 19 7592
TEST_TIME 20 7846
TEST_TIME 21 8544
TEST_TIME 22 8470
TEST_TIME 23 8400
TEST_TIME 24 8632
TEST_TIME 25 8645
TEST_TIME 26 8635
TEST_TIME 27 8946
TEST_TIME 28 8873
TEST_TIME 29 9264
TEST_TIME 30 9481
TEST_TIME 31 10116
TEST_TIME 32 10989
TEST_TIME 33 10766
TEST_TIME 34 10582
TEST_TIME 35 10507
TEST_TIME 36 10629
TEST_TIME 37 10499
TEST_TIME 38 10317
TEST_TIME 39 10265
TEST_TIME 40 10138
TEST_TIME 41 10040
TEST_TIME 42 10084
TEST_TIME 43 10082
TEST_TIME 44 10047
TEST_TIME 45 9900
TEST_TIME 46 9878
TEST_TIME 47 9833
TEST_TIME 48 9730
TEST_TIME 49 9687
TEST_TIME 50 9760
TEST_TIME 51 9606
TEST_TIME 52 9582
TEST_TIME 53 9533
TEST_TIME 54 9528
TEST_TIME 55 9475
TEST_TIME 56 9387
TEST_TIME 57 9291
TEST_TIME 58 9238
TEST_TIME 59 9091
TEST_TIME 60 9113
TEST_TIME 61 9096
TEST_TIME 62 9102
TEST_TIME 63 9180
TEST_TIME 64 9202
TEST_TIME 65 9214
TEST_TIME 66 9181
TEST_TIME 67 9303
TEST_TIME 68 9263
TEST_TIME 69 9333
TEST_TIME 70 9429
TEST_TIME 71 9393
TEST_TIME 72 9444
TEST_TIME 73 9437
TEST_TIME 74 9367
TEST_TIME 75 9371
TEST_TIME 76 9291
TEST_TIME 77 9259
TEST_TIME 78 9190
TEST_TIME 79 9283
TEST_TIME 80 9245
TEST_TIME 81 9205
TEST_TIME 82 9147
TEST_TIME 83 9119
TEST_TIME 84 9082
TEST_TIME 85 9083
TEST_TIME 86 9057
TEST_TIME 87 9083
TEST_TIME 88 9059
TEST_TIME 89 9084
TEST_TIME 90 9151
TEST_TIME 91 9210
TEST_TIME 92 9170
TEST_TIME 93 9234
TEST_TIME 94 9237
TEST_TIME 95 9418
TEST_TIME 96 9390
TEST_TIME 97 9484
TEST_TIME 98 9513
TEST_TIME 99 9492
TEST_TIME 100 9455
TEST_TIME 101 9388
TEST_TIME 102 9394
TEST_TIME 103 9353
TEST_TIME 104 9372
TEST_TIME 105 9309
TEST_TIME 106 9276
TEST_TIME 107 9233
TEST_TIME 108 9235
TEST_TIME 109 9244
TEST_TIME 110 9239
TEST_TIME 111 9214
TEST_TIME 112 9214
TEST_TIME 113 9216
TEST_TIME 114 9230
TEST_TIME 115 9268
TEST_TIME 116 9273
TEST_TIME 117 9278
TEST_TIME 118 9300
TEST_TIME 119 9287
TEST_TIME 120 9275

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-06-2022 19:39:26 to 06-06-2022 19:39:29.

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 1536350 1536350 0 0.00 0 23469 7 0 0
doc_list_biud 1536350 1536350 0 0.00 0 32802 29 0 0
sp_add_invoice_to_stock 5103 4976 127 2.48 50 195924 19298 0 127
sp_cancel_adding_invoice 5851 5851 0 0.00 4 57451 852 0 0
sp_cancel_client_order 1763 1763 0 0.00 4 13535 357 0 0
sp_cancel_customer_reserve 569465 569465 0 0.00 1 28379 62 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 2 2482 86 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 3970 77 0 0
sp_cancel_supplier_invoice 6457 6448 9 0.13 1 27580 827 0 9
sp_cancel_supplier_order 426 426 0 0.00 9 1037577 104975 0 0
sp_cancel_write_off 10627 10627 0 0.00 1 23648 135 0 0
sp_client_order 20370 20370 0 0.00 3 44271 391 0 0
sp_customer_reserve 456840 456840 0 0.00 0 64357 191 0 0
sp_fill_shopping_cart 31037 31037 0 0.00 1 15869 42 0 0
sp_fill_shopping_cart_clo_res 456839 456839 0 0.00 0 17964 17 0 0
sp_get_clo_for_invoice 4975 4975 0 0.00 0 40470 384 0 0
sp_kill_cost_storno 17540 17540 0 0.00 0 23467 68 0 0
sp_kill_qstorno_ret_qs2qd 1156431 1156431 0 0.00 0 28373 33 0 0
sp_kill_qty_storno 609561 609561 0 0.00 0 32800 72 0 0
sp_lock_selected_doc 20770 20770 0 0.00 0 9235 5 0 0
sp_make_cost_storno 27985 27985 0 0.00 0 21057 65 0 0
sp_multiply_rows_for_pdistr 27985 27985 0 0.00 0 22697 38 0 0
sp_multiply_rows_for_qdistr 20370 20370 0 0.00 0 32668 166 0 0
sp_pay_from_customer 7133 7133 0 0.00 4 22858 148 0 0
sp_pay_to_supplier 5871 5871 0 0.00 4 22654 153 0 0
sp_payment_common 13004 13004 0 0.00 1 19679 42 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 0 13530 327 0 0
sp_qd_handle_on_invoice_upd_sts 10826 10826 0 0.00 0 32797 348 0 0
sp_qd_handle_on_reserve_upd_sts 10006 10006 0 0.00 0 30814 69 0 0
sp_reserve_write_off 10006 10006 0 0.00 6 30832 215 0 0
sp_supplier_invoice 5294 5294 0 0.00 6 103183 3675 0 0
sp_supplier_order 5374 5374 0 0.00 4 42706 1272 0 0
srv_aggregate_perf_data 98 98 0 0.00 2623 347459 23891 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1326 125113 45920 0 0
srv_find_qd_qs_mism 1065923 1065923 0 0.00 0 19780 5 0 0
srv_make_invnt_saldo 549 549 0 0.00 43 46564 1387 0 0
srv_make_money_saldo 816 816 0 0.00 1 12477 50 0 0
srv_recalc_idx_stat 4 4 0 0.00 154016 514468 259381 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4976 4976 0 0.00 0 11546 186 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 1 1981 76 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 5240 14 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 1061 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 3252 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 4628 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 2757 24 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 25492 1698 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 2006 7 0 0
t$perf-norm:sp_client_order 20370 20370 0 0.00 0 9896 20 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 5920 19 0 0
t$perf-norm:sp_pay_from_customer 7133 7133 0 0.00 0 2505 7 0 0
t$perf-norm:sp_pay_to_supplier 5871 5871 0 0.00 0 8980 11 0 0
t$perf-norm:sp_reserve_write_off 10006 10006 0 0.00 0 8214 12 0 0
t$perf-norm:sp_supplier_invoice 5294 5294 0 0.00 0 7556 20 0 0
t$perf-norm:sp_supplier_order 5374 5374 0 0.00 0 8430 19 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 249 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 711 11 0 0
t$perf-norm:srv_make_invnt_saldo 549 549 0 0.00 0 3155 31 0 0
t$perf-norm:srv_make_money_saldo 816 816 0 0.00 0 837 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 18 6 0 0
v_add_invoice_to_stock 10204 9950 254 2.48 0 7083 19 0 254
v_all_customers 37016 37016 0 0.00 0 28 1 0 0
v_all_suppliers 29786 29786 0 0.00 0 20 1 0 0
v_all_wares 40740 40740 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 536 8 0 0
v_cancel_client_order 3526 3526 0 0.00 0 2424 6 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 2474 14 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 2784 6 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 3962 11 0 0
v_cancel_supplier_invoice 794 776 18 2.26 0 1061 15 0 18
v_cancel_supplier_order 852 852 0 0.00 0 1321 5 0 0
v_cancel_write_off 2036 2036 0 0.00 0 1485 12 0 0
v_max_id_clo_ord 5372 5372 0 0.00 0 539 1 0 0
v_max_id_clo_res 991 991 0 0.00 0 3192 19 0 0
v_max_id_ord_sup 5293 5293 0 0.00 0 7020 5 0 0
v_max_non_paid_invoice 1643 1643 0 0.00 0 2718 4 0 0
v_max_non_paid_realizn 3902 3902 0 0.00 0 3194 5 0 0
v_min_id_clo_ord 5372 5372 0 0.00 0 1432 5 0 0
v_min_id_clo_res 991 991 0 0.00 0 2532 8 0 0
v_min_id_ord_sup 5293 5293 0 0.00 0 4423 7 0 0
v_min_non_paid_invoice 5871 5871 0 0.00 0 12017 33 0 0
v_min_non_paid_realizn 7133 7133 0 0.00 0 19673 34 0 0
v_our_firm 10186 10186 0 0.00 0 14 1 0 0
v_reserve_write_off 20012 20012 0 0.00 0 4863 7 0 0
x_lock_depdocs_on_canc_invoice 11911 11911 0 0.00 0 13471 124 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 17159 402 0 0
x_make_qstorno_1000_1200 5374 5374 0 0.00 1 42675 1192 0 0
x_make_qstorno_1200_2000 5294 5294 0 0.00 3 103125 3519 0 0
x_make_qstorno_2100_3300 456840 456840 0 0.00 0 60635 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-06-2022 19:39:30 to 06-06-2022 19:39:34.

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:23 12 8192000000 8801703568 8814231552 6655264 0.081 3 1 2 0 6756192 58144 42784
17:09:24 15791 8192000000 9888856240 10192977920 968304816 10.570 100 88 176 0 997623056 5506624 23811616
17:10:50 13426 8192000000 9946697264 10270437376 1034745360 11.214 100 88 176 0 1065450256 8259200 22445696
17:12:10 19120 8192000000 9996104192 10333388800 1081578128 11.663 100 84 168 1 1111931616 9458384 20895104
17:13:31 13951 8192000000 10036679984 10385272832 1122272688 12.048 100 85 170 0 1154104000 11176368 20654944
17:14:49 6870 8192000000 10082456688 10440593408 1166404032 12.463 100 87 173 1 1199789184 13247920 20137232
17:16:00 5180 8192000000 10136156784 10501623808 1218145904 12.945 100 86 171 2 1251683792 13987600 19550288
17:17:07 2884 8192000000 10170523520 10546122752 1251079392 13.248 100 87 171 1 1284029008 14607168 18342448
17:18:12 1509 8192000000 10193004544 10570432512 1275407312 13.471 100 90 181 0 1309307600 13746592 20153696
17:19:15 1755 8192000000 10220965264 10604486656 1302016528 13.714 100 93 188 1 1336211168 13381680 20812960
17:20:18 2407 8192000000 10219343840 10608934912 1307151936 13.760 100 83 167 1 1334111152 8059760 18899456
17:21:22 4641 8192000000 10203109104 10615996416 1318187248 13.860 100 33 66 0 1332314304 6923744 7203312
17:22:27 7895 8192000000 10205895872 10624684032 1321200048 13.888 100 22 44 0 1333472976 7349280 4923648
17:23:36 4128 8192000000 10217445984 10634809344 1329738816 13.965 100 21 41 0 1341176432 7046432 4391184
17:24:41 43523 8192000000 10230102656 10646474752 1334315248 14.006 100 34 67 0 1347770000 5861680 7593072
17:26:25 9298 8192000000 10224482576 10647187456 1338030976 14.040 100 18 36 0 1347229232 4968208 4230048
17:27:35 7909 8192000000 10225469504 10642534400 1326824464 13.938 99 15 28 1 1335035952 4791104 3420384
17:28:49 15180 8192000000 10174265040 10580058112 1220998832 12.971 93 24 48 2 1231825072 4708000 6118240
17:30:04 13400 8192000000 10159278576 10565644288 1180044976 12.591 91 32 65 0 1198445360 11524624 6875760
17:31:18 2927 8192000000 10162815312 10556944384 1247022144 13.211 99 33 67 1 1264931024 9962816 7946064
17:32:22 10075 8192000000 10141560048 10541527040 1196754544 12.746 95 17 33 1 1206194144 5472256 3967344
17:33:35 50660 8192000000 10183437120 10584162304 1268692976 13.410 99 32 64 3 1281378864 5313072 7372816
17:35:43 8762 8192000000 10182656288 10595106816 1277497712 13.490 99 25 49 3 1292746480 9222288 6026480
17:36:52 6707 8192000000 10202090304 10613084160 1309170976 13.779 100 17 34 0 1318535840 5388848 3976016
17:38:00 16979 8192000000 10210646480 10630664192 1319933040 13.876 100 12 24 0 1327335904 4827104 2575760
17:39:22 29446 8192000000 10257117680 10677776384 1358444400 14.223 100 44 87 2 1376184528 6504032 11236096
17:40:54 28629 8192000000 10273226736 10693087232 1366791600 14.298 100 47 94 3 1391044976 13339040 10914336
17:42:34 33626 8192000000 10282097488 10706817024 1375672672 14.378 100 43 86 2 1399039728 13327840 10039216
17:44:13 28489 8192000000 10305610912 10726309888 1391360144 14.518 100 51 101 2 1418954256 15620848 11973264
17:45:45 8069 8192000000 10311517296 10737451008 1398137952 14.578 100 51 101 3 1427995552 17808752 12048848
17:47:00 31254 8192000000 10318290976 10737422336 1396412624 14.563 100 47 94 0 1424735232 17204880 11117728
17:48:37 8525 8192000000 10328097344 10745999360 1404869424 14.638 100 59 117 3 1438991424 20137808 13984192
17:49:52 6526 8192000000 10340218416 10755403776 1405583568 14.645 100 77 152 0 1445977680 22754528 17639584
17:51:11 4238 8192000000 10327223600 10738024448 1388344800 14.491 100 71 142 1 1433804528 28304160 17155568
17:52:21 9799 8192000000 10333609744 10744897536 1391786304 14.522 100 75 149 0 1441655456 32056080 17813072
17:53:38 1708 8192000000 10301045136 10708996096 1356014288 14.202 99 82 162 4 1409620720 33377712 20228720
17:54:44 3608 8192000000 10303252288 10710188032 1358997968 14.228 100 58 116 2 1405728960 32684176 14046816
17:55:52 4025 8192000000 10339582096 10742075392 1379645552 14.413 100 87 174 2 1439041104 38279664 21115888
17:57:01 1326 8192000000 10319282112 10717798400 1359947488 14.237 100 84 168 0 1419716480 39055328 20713664
17:58:07 4691 8192000000 10328410208 10726199296 1355916928 14.201 100 79 158 1 1427917104 51949184 20050992
17:59:17 3139 8192000000 10351030544 10752462848 1378012304 14.399 100 82 164 0 1452243840 53666320 20565216
18:00:23 8337 8192000000 10327157968 10743660544 1371275216 14.338 99 49 97 2 1425687472 41481472 12930784
18:01:39 5237 8192000000 10360204128 10775339008 1396321952 14.562 100 69 139 1 1467124112 53610608 17191552
18:02:53 17534 8192000000 10356316768 10784227328 1402878992 14.621 100 34 69 1 1462650432 50364768 9406672
18:04:14 14899 8192000000 10366006960 10794696704 1403547376 14.627 100 42 83 0 1470652256 55710752 11394128
18:05:35 33355 8192000000 10346627120 10776354816 1389837296 14.504 100 31 62 1 1462241712 63298336 9106080
18:07:12 48278 8192000000 10330802304 10781020160 1366012032 14.291 99 28 57 1 1439635296 64931440 8691824
18:09:03 16756 8192000000 10342232528 10783842304 1371317024 14.339 100 27 54 0 1446976096 67649040 8010032
18:10:29 23174 8192000000 10297207280 10740174848 1373617488 14.359 100 17 34 0 1400052272 22280112 4154672
18:11:55 81444 8192000000 10296882000 10733944832 1372505104 14.349 100 42 86 0 1395416800 11387264 11524432
18:14:18 53224 8192000000 10294456528 10739675136 1371315408 14.339 99 22 44 1 1389321152 13378704 4627040
18:16:16 54382 8192000000 10301563376 10753052672 1391376064 14.518 100 22 45 0 1405800848 9426832 4997952
18:18:12 66271 8192000000 10321124400 10762121216 1377126848 14.391 98 18 36 0 1399160192 17708176 4325168
18:20:19 116164 8192000000 10310596480 10782720000 1393546368 14.537 100 13 25 0 1422145808 25273568 3325872
18:23:15 125113 8192000000 10317629552 10773323776 1401555936 14.609 100 39 78 1 1423753920 13327728 8870256
18:26:21 43945 8192000000 10308527392 10757529600 1404648256 14.636 100 46 94 3 1423396992 7701152 11047584
18:28:14 68781 8192000000 10306624848 10778079232 1393925408 14.541 99 11 22 1 1414140528 17819776 2395344
18:30:30 28244 8192000000 10311293600 10771271680 1412875248 14.709 100 13 26 1 1422702016 7002768 2824000
18:32:06 29651 8192000000 10314699520 10771152896 1409469664 14.679 100 18 35 4 1423044560 8951712 4623184
18:33:37 35977 8192000000 10280829840 10743709696 1368670720 14.315 98 11 22 1 1378762672 7852032 2239920
18:35:24 55839 8192000000 10280746064 10736160768 1348010512 14.130 97 15 30 2 1360708416 9093696 3604208
18:37:34 83378 8192000000 10280348736 10743111680 1349324512 14.141 97 30 58 1 1371509808 15248160 6937136
18:40:04 37760 8192000000 10290174144 10746642432 1361568896 14.251 98 12 24 1 1376896256 12579904 2747456
18:41:42 41277 8192000000 10260924384 10720165888 1347994752 14.129 98 18 36 1 1360004080 8110128 3899200
18:43:24 46625 8192000000 10270854336 10724216832 1328375712 13.952 96 24 48 1 1341576496 7724160 5476624
18:45:22 95308 8192000000 10271207872 10721095680 1353352784 14.178 98 11 22 0 1372825520 16981360 2491376
18:47:58 101604 8192000000 10278173200 10729316352 1299962672 13.695 94 16 33 1 1318232256 14624288 3645296
18:50:48 94183 8192000000 10288036176 10738315264 1335937616 14.021 96 16 32 0 1355092704 15492416 3662672
18:53:22 93264 8192000000 10281578864 10726252544 1363553008 14.269 99 38 74 1 1382331072 10431872 8346192
18:56:03 63829 8192000000 10280990752 10730885120 1368104800 14.310 100 31 62 1 1393663296 18374752 7183744
18:58:09 62931 8192000000 10240245936 10695327744 1285099456 13.560 94 10 20 0 1297040800 9879328 2062016
19:00:12 40891 8192000000 10259053120 10704961536 1343132720 14.086 99 13 26 1 1355867152 9897152 2837280
19:02:02 84384 8192000000 10261093168 10727223296 1356051632 14.202 99 14 28 1 1369267536 10193120 3022784
19:04:30 31976 8192000000 10262415504 10718900224 1346176976 14.113 98 14 28 0 1356262608 7101936 2983696
19:06:03 31019 8192000000 10287158992 10736320512 1368473056 14.313 100 30 60 2 1382234032 6998752 6762224
19:07:44 70340 8192000000 10292280704 10746978304 1385086176 14.462 100 17 34 1 1408585872 19536144 3963552
19:10:04 48967 8192000000 10313089216 10757910528 1392015616 14.524 100 27 52 1 1408574592 10966032 5592944
19:11:55 52243 8192000000 10332022368 10793443328 1405851216 14.647 100 8 16 1 1428535824 20628928 2055680
19:13:48 85556 8192000000 10329385808 10799984640 1394886656 14.549 99 13 26 0 1420632816 23031600 2714560
19:16:17 111448 8192000000 10305572688 10760454144 1323132352 13.905 94 13 26 0 1338994848 13480240 2382256
19:19:11 97645 8192000000 10286534992 10743599104 1355764784 14.199 97 36 72 1 1373640736 10116176 7759776
19:21:49 28970 8192000000 10289581440 10752012288 1395776832 14.557 100 18 36 0 1408132960 8141744 4214384
19:23:19 31538 8192000000 10308796016 10760507392 1390145344 14.507 99 5 10 0 1402791344 11444224 1201776
19:24:51 71158 8192000000 10303830384 10769342464 1404466096 14.635 100 13 27 0 1420835568 13250432 3119040
19:27:25 78121 8192000000 10275555312 10734325760 1354816848 14.191 98 10 20 1 1366542752 9172256 2553648
19:29:44 38090 8192000000 10306174096 10760708096 1396339360 14.562 100 42 84 1 1419184592 12882448 9962784
19:31:30 56340 8192000000 10280215472 10735198208 1361006352 14.246 98 20 40 1 1378133280 12798320 4328608
19:33:27 50984 8192000000 10237234688 10684276736 1234322480 13.094 91 15 30 0 1252452080 14943840 3185760
19:35:23 56409 8192000000 10264919648 10723770368 1360666800 14.243 100 34 68 0 1384474672 16410816 7397056
19:37:29 64817 8192000000 9519837184 9850306560 631689472 7.159 47 11 22 0 643979312 9938192 2351648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-06-2022 19:39:36 to 06-06-2022 19:39:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-06-2022 19:39:52 to 06-06-2022 19:39:54.

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          240687
MON$OLDEST_ACTIVE               241647
MON$OLDEST_SNAPSHOT             241647
MON$NEXT_TRANSACTION            241647
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                       2049504
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:119: 
:120: mscs-test-161.budzdorov.ru    Mon Jun  6 19:38:36 2022
:121:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:122: 
--- end of diff output ---

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

06.06.22 19:39:55. 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-06-06 19:39:57 No severe PSQL-related problems occured
06.06.22 19:39:58. End of report.