Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.06.2022 07:48
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 16009028 kB
MemAvailable 31183440 kB
Buffers 0 kB
Cached 15667288 kB
SwapCached 2508 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-25 07:47:29
DTS_END                         2022-06-25 07:47:29.7990
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(68:17)
IP                              ::1/60760
TRN_ID                          222453
ATT_ID                          597
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: 9067.52 21062 1088102
customer order: creation 181.63 489 21796
customer order: refuse 15.95 415 1914
order to supplier: creation 46.87 1345 5624
order to supplier: removal 3.27 166394 392
invoice (draft): creation 46.36 2916 5563
invoice (draft): removal 48.07 1033 5768
invoice accept: apply 43.66 25838 5239
invoice accept: cancel 43.06 1334 5167
customer reserve: creation 4079.48 243 489537
customer reserve: removal 4258.04 101 510965
realization accept: apply 87.18 283 10461
realization accept: cancel 80.56 182 9667
payment from customer: creation 60.04 185 7205
payment from customer: removal 5.46 141 655
payment to supplier: creation 51.10 213 6132
payment to supplier: removal 3.79 140 455
service: total inventory turnovers 4.03 3999 484
service: total monetary turnovers 7.88 363 946
service: aggregate perf. data 1.05 13352 126
service: refresh index statistics 0.05 202281 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-06-2022 07:48:43 to 25-06-2022 07:48:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8442
TEST_TIME 2 7645
TEST_TIME 3 8100
TEST_TIME 4 7443
TEST_TIME 5 7069
TEST_TIME 6 6703
TEST_TIME 7 6460
TEST_TIME 8 6444
TEST_TIME 9 6349
TEST_TIME 10 6244
TEST_TIME 11 6636
TEST_TIME 12 6912
TEST_TIME 13 6863
TEST_TIME 14 6885
TEST_TIME 15 6994
TEST_TIME 16 7273
TEST_TIME 17 7494
TEST_TIME 18 7746
TEST_TIME 19 7767
TEST_TIME 20 7826
TEST_TIME 21 8031
TEST_TIME 22 8197
TEST_TIME 23 8209
TEST_TIME 24 8402
TEST_TIME 25 8417
TEST_TIME 26 8183
TEST_TIME 27 8169
TEST_TIME 28 8542
TEST_TIME 29 8898
TEST_TIME 30 9587
TEST_TIME 31 9766
TEST_TIME 32 10375
TEST_TIME 33 11044
TEST_TIME 34 10803
TEST_TIME 35 10851
TEST_TIME 36 10793
TEST_TIME 37 10654
TEST_TIME 38 10635
TEST_TIME 39 10770
TEST_TIME 40 10666
TEST_TIME 41 10456
TEST_TIME 42 10395
TEST_TIME 43 10307
TEST_TIME 44 10109
TEST_TIME 45 10059
TEST_TIME 46 10015
TEST_TIME 47 9944
TEST_TIME 48 9946
TEST_TIME 49 9919
TEST_TIME 50 9921
TEST_TIME 51 9877
TEST_TIME 52 9828
TEST_TIME 53 9772
TEST_TIME 54 9805
TEST_TIME 55 9735
TEST_TIME 56 9771
TEST_TIME 57 9769
TEST_TIME 58 9731
TEST_TIME 59 9759
TEST_TIME 60 9702
TEST_TIME 61 9707
TEST_TIME 62 9766
TEST_TIME 63 9804
TEST_TIME 64 9773
TEST_TIME 65 9835
TEST_TIME 66 9779
TEST_TIME 67 9823
TEST_TIME 68 9783
TEST_TIME 69 9750
TEST_TIME 70 9647
TEST_TIME 71 9652
TEST_TIME 72 9619
TEST_TIME 73 9580
TEST_TIME 74 9503
TEST_TIME 75 9493
TEST_TIME 76 9446
TEST_TIME 77 9422
TEST_TIME 78 9374
TEST_TIME 79 9406
TEST_TIME 80 9368
TEST_TIME 81 9361
TEST_TIME 82 9338
TEST_TIME 83 9459
TEST_TIME 84 9428
TEST_TIME 85 9395
TEST_TIME 86 9524
TEST_TIME 87 9473
TEST_TIME 88 9477
TEST_TIME 89 9489
TEST_TIME 90 9473
TEST_TIME 91 9408
TEST_TIME 92 9351
TEST_TIME 93 9335
TEST_TIME 94 9268
TEST_TIME 95 9262
TEST_TIME 96 9286
TEST_TIME 97 9278
TEST_TIME 98 9255
TEST_TIME 99 9261
TEST_TIME 100 9260
TEST_TIME 101 9272
TEST_TIME 102 9304
TEST_TIME 103 9287
TEST_TIME 104 9371
TEST_TIME 105 9379
TEST_TIME 106 9370
TEST_TIME 107 9342
TEST_TIME 108 9391
TEST_TIME 109 9385
TEST_TIME 110 9312
TEST_TIME 111 9291
TEST_TIME 112 9244
TEST_TIME 113 9227
TEST_TIME 114 9177
TEST_TIME 115 9180
TEST_TIME 116 9183
TEST_TIME 117 9156
TEST_TIME 118 9156
TEST_TIME 119 9145
TEST_TIME 120 9094

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-06-2022 07:48:47 to 25-06-2022 07:48: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 1538741 1538741 0 0.00 0 33681 14 0 0
doc_list_biud 1538731 1538731 0 0.00 0 36563 40 0 0
sp_add_invoice_to_stock 5350 5239 111 2.07 67 184336 25839 0 111
sp_cancel_adding_invoice 5167 5167 0 0.00 6 78540 1334 0 0
sp_cancel_client_order 1914 1914 0 0.00 5 16273 415 0 0
sp_cancel_customer_reserve 510965 510965 0 0.00 1 36570 102 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 4 7253 141 0 0
sp_cancel_pay_to_supplier 455 455 0 0.00 4 4574 140 0 0
sp_cancel_supplier_invoice 5778 5768 10 0.17 2 14014 1033 0 10
sp_cancel_supplier_order 392 392 0 0.00 15 1331878 166394 0 0
sp_cancel_write_off 9667 9667 0 0.00 2 7892 182 0 0
sp_client_order 21796 21796 0 0.00 5 36078 490 0 0
sp_customer_reserve 489537 489537 0 0.00 1 132213 244 0 0
sp_fill_shopping_cart 32977 32977 0 0.00 2 15970 83 0 0
sp_fill_shopping_cart_clo_res 489509 489509 0 0.00 0 16830 19 0 0
sp_get_clo_for_invoice 5209 5209 0 0.00 0 24176 277 0 0
sp_kill_cost_storno 15944 15944 0 0.00 0 9652 73 0 0
sp_kill_qstorno_ret_qs2qd 1037757 1037757 0 0.00 0 36555 44 0 0
sp_kill_qty_storno 549540 549540 0 0.00 0 36563 102 0 0
sp_lock_selected_doc 21732 21732 0 0.00 0 11968 10 0 0
sp_make_cost_storno 29007 29007 0 0.00 0 15506 83 0 0
sp_multiply_rows_for_pdistr 29004 29004 0 0.00 0 7566 39 0 0
sp_multiply_rows_for_qdistr 21796 21796 0 0.00 0 35796 185 0 0
sp_pay_from_customer 7205 7205 0 0.00 5 16169 185 0 0
sp_pay_to_supplier 6132 6132 0 0.00 6 18645 214 0 0
sp_payment_common 13337 13337 0 0.00 2 7544 49 0 0
sp_qd_handle_on_cancel_clo 1914 1914 0 0.00 0 16267 333 0 0
sp_qd_handle_on_invoice_upd_sts 10375 10375 0 0.00 0 17461 523 0 0
sp_qd_handle_on_reserve_upd_sts 10459 10459 0 0.00 0 18323 59 0 0
sp_reserve_write_off 10461 10461 0 0.00 8 18353 283 0 0
sp_supplier_invoice 5563 5563 0 0.00 9 72484 2917 0 0
sp_supplier_order 5624 5624 0 0.00 8 22228 1346 0 0
srv_aggregate_perf_data 126 126 0 0.00 2205 89641 13352 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 729 143961 35398 0 0
srv_find_qd_qs_mism 1036929 1036929 0 0.00 0 33680 11 0 0
srv_make_invnt_saldo 484 484 0 0.00 66 131355 3999 0 0
srv_make_money_saldo 946 946 0 0.00 2 13312 364 0 0
srv_recalc_idx_stat 6 6 0 0.00 58550 375378 202281 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5245 5245 0 0.00 1 20383 944 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 1 6988 458 0 0
t$perf-norm:sp_cancel_client_order 1914 1914 0 0.00 0 1140 28 0 0
t$perf-norm:sp_cancel_customer_reserve 740 740 0 0.00 0 5383 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 1005 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 455 455 0 0.00 0 3418 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 1223 33 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 1 85628 6585 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 1930 25 0 0
t$perf-norm:sp_client_order 21796 21796 0 0.00 0 28861 37 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 1048 14 0 0
t$perf-norm:sp_pay_from_customer 7205 7205 0 0.00 0 5383 19 0 0
t$perf-norm:sp_pay_to_supplier 6132 6132 0 0.00 0 2343 18 0 0
t$perf-norm:sp_reserve_write_off 10461 10461 0 0.00 0 3176 22 0 0
t$perf-norm:sp_supplier_invoice 5563 5563 0 0.00 0 22393 36 0 0
t$perf-norm:sp_supplier_order 5624 5624 0 0.00 0 1448 31 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 879 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 253 8 0 0
t$perf-norm:srv_make_invnt_saldo 484 484 0 0.00 0 1153 16 0 0
t$perf-norm:srv_make_money_saldo 946 946 0 0.00 0 1737 7 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 3 319 107 0 0
v_add_invoice_to_stock 10636 10414 222 2.08 0 11666 28 0 222
v_all_customers 39768 39768 0 0.00 0 97 4 0 0
v_all_suppliers 31504 31504 0 0.00 0 252 5 0 0
v_all_wares 43590 43590 0 0.00 0 74 4 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 734 14 0 0
v_cancel_client_order 3828 3828 0 0.00 0 4475 14 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 711 11 0 0
v_cancel_customer_reserve 1480 1480 0 0.00 0 4362 16 0 0
v_cancel_payment_to_supplier 910 910 0 0.00 0 4452 19 0 0
v_cancel_supplier_invoice 882 862 20 2.26 0 4119 28 0 20
v_cancel_supplier_order 784 784 0 0.00 0 1469 12 0 0
v_cancel_write_off 2058 2058 0 0.00 0 1723 26 0 0
v_max_id_clo_ord 5623 5623 0 0.00 0 684 5 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 3515 23 0 0
v_max_id_ord_sup 5559 5559 0 0.00 0 1695 6 0 0
v_max_non_paid_invoice 1561 1561 0 0.00 0 3289 11 0 0
v_max_non_paid_realizn 3637 3637 0 0.00 0 3413 10 0 0
v_min_id_clo_ord 5623 5623 0 0.00 0 3961 9 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 1843 9 0 0
v_min_id_ord_sup 5559 5559 0 0.00 0 8746 12 0 0
v_min_non_paid_invoice 6132 6132 0 0.00 0 7539 18 0 0
v_min_non_paid_realizn 7205 7205 0 0.00 0 6658 18 0 0
v_our_firm 10958 10958 0 0.00 0 75 5 0 0
v_reserve_write_off 20918 20918 0 0.00 0 14668 13 0 0
x_lock_depdocs_on_canc_invoice 10504 10504 0 0.00 0 10203 116 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 5782 346 0 0
x_make_qstorno_1000_1200 5624 5624 0 0.00 3 22201 1170 0 0
x_make_qstorno_1200_2000 5563 5563 0 0.00 4 72460 2693 0 0
x_make_qstorno_2100_3300 489536 489536 0 0.00 0 132211 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-06-2022 07:48:51 to 25-06-2022 07:48: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
05:17:33 926 8192000000 8921793200 8977145856 11305056 0.137 3 2 4 0 11626928 109136 212736
05:18:35 26760 8192000000 9722874208 10019123200 802457216 8.921 100 89 176 1 831502064 7612624 21432224
05:20:06 21289 8192000000 9753460528 10060300288 828165744 9.181 100 88 176 0 861078272 11807696 21104832
05:21:32 21942 8192000000 9798504128 10119094272 860180976 9.502 100 88 174 0 911594208 30829936 20583296
05:23:13 7796 8192000000 9862912880 10197696512 920326768 10.099 100 70 140 0 976268304 40990512 14951024
05:24:24 3471 8192000000 9911405120 10257375232 964593664 10.534 100 79 155 0 1024854144 44721920 15538560
05:25:30 3915 8192000000 9955590624 10311557120 998508544 10.864 100 77 149 1 1080559664 67102800 14948320
05:26:36 1611 8192000000 9999751248 10364309504 1022071968 11.092 100 90 170 0 1114652080 75342144 17237968
05:27:39 1924 8192000000 10029647008 10404499456 1039365600 11.259 100 98 182 4 1152555200 94649472 18540128
05:28:47 1090 8192000000 10047164800 10422071296 1056917824 11.427 100 98 191 0 1162812736 87137024 18757888
05:29:49 3529 8192000000 10054708624 10437373952 1070597856 11.558 100 86 158 0 1181778688 95632624 15548208
05:30:57 938 8192000000 10057374544 10439872512 1077405488 11.623 100 96 187 1 1179867072 84228592 18232992
05:31:58 1164 8192000000 10070361104 10456719360 1084394752 11.689 100 95 173 0 1190567200 88882080 17290368
05:33:00 3245 8192000000 10058580992 10453483520 1084283264 11.688 100 66 128 0 1173807024 76362784 13160976
05:34:05 1075 8192000000 10065106672 10453835776 1089256144 11.736 100 93 184 0 1187627232 79604208 18766880
05:35:07 1099 8192000000 10062985952 10453299200 1093300128 11.774 100 99 194 1 1187793168 74521056 19971984
05:36:09 1199 8192000000 10070616768 10457178112 1094453520 11.785 100 96 187 1 1191624336 77316592 19854224
05:37:11 932 8192000000 10072410112 10464268288 1098495184 11.823 100 93 176 1 1191299360 75266688 17537488
05:38:12 1373 8192000000 10064423344 10460884992 1094936432 11.790 100 84 158 5 1186421232 75343920 16140880
05:39:16 801 8192000000 10046158560 10438078464 1088969104 11.733 100 89 169 1 1169682128 62943984 17769040
05:40:18 999 8192000000 10036253936 10425307136 1080662224 11.654 100 87 164 2 1162689232 64415904 17611104
05:41:20 1024 8192000000 10028092032 10413277184 1054513344 11.404 100 92 168 2 1150786256 79162608 17110304
05:42:22 1154 8192000000 10016435536 10393485312 1039446512 11.259 100 90 170 3 1137620832 80593936 17580384
05:43:24 1937 8192000000 9975085696 10352951296 1027247856 11.142 98 70 134 2 1093972992 52971120 13754016
05:44:27 1467 8192000000 9991323760 10370990080 1031086896 11.179 100 71 138 2 1112084560 66048640 14949024
05:45:29 10064 8192000000 9987268400 10366185472 1032654592 11.194 99 61 115 4 1114397712 69998848 11744272
05:46:40 1468 8192000000 9977483328 10355990528 1049564112 11.356 100 94 181 4 1099678624 30967088 19147424
05:47:46 3107 8192000000 9987054064 10369458176 1057105264 11.429 99 71 136 1 1106441088 35736544 13599280
05:48:53 3075 8192000000 10034657376 10418425856 1086692176 11.711 100 88 151 3 1157994128 56596160 14705792
05:50:02 1009 8192000000 10078968016 10461155328 1107368432 11.907 100 95 181 1 1200746864 75384528 17993904
05:51:07 5053 8192000000 10086436000 10477015040 1110156896 11.934 100 74 144 0 1206692512 81580128 14955488
05:52:19 3422 8192000000 10107443888 10514345984 1122805920 12.053 100 56 101 2 1222671344 88202240 11663184
05:53:27 8958 8192000000 10152431232 10548748288 1132860144 12.148 100 79 153 2 1268322528 118950304 16512080
05:54:42 4735 8192000000 10189716384 10595061760 1155879152 12.365 100 69 132 0 1304733776 134360672 14493952
05:55:55 9108 8192000000 10234786352 10635894784 1159334048 12.397 100 80 156 1 1350957424 173681632 17941744
05:57:10 2109 8192000000 10261623952 10669703168 1169854320 12.495 100 79 153 0 1381026096 193861696 17310080
05:58:18 2182 8192000000 10290972256 10711814144 1177264656 12.565 100 76 150 0 1400872544 206642176 16965712
05:59:24 5628 8192000000 10295497808 10708353024 1185057376 12.637 100 82 162 1 1410565664 208184336 17323952
06:00:35 1234 8192000000 10345357552 10747392000 1194111360 12.722 100 96 185 0 1451489152 235700320 21677472
06:01:41 848 8192000000 10383719680 10786111488 1199501792 12.772 100 98 193 0 1492091360 270095344 22494224
06:02:43 1650 8192000000 10406035024 10836291584 1198969920 12.767 100 91 181 0 1516022832 295881872 21171040
06:03:49 812 8192000000 10412650880 10824597504 1195231952 12.732 100 96 187 0 1524189840 307312544 21645344
06:04:52 923 8192000000 10414980512 10859057152 1190740160 12.690 100 96 180 0 1520534256 307540048 22254048
06:05:59 3501 8192000000 10426514112 10843791360 1197814336 12.756 100 74 139 2 1531970624 317838256 16318032
06:07:06 723 8192000000 10436012592 10861740032 1198002480 12.758 100 93 179 1 1541321824 323063488 20255856
06:08:09 1046 8192000000 10463257344 10883174400 1191677952 12.699 100 97 191 0 1569202368 355797584 21726832
06:09:12 871 8192000000 10475454784 10907471872 1192907824 12.710 100 97 188 1 1578918352 364766432 21244096
06:10:16 957 8192000000 10491832656 10918010880 1192864496 12.710 100 98 194 3 1596301056 379904768 23531792
06:11:18 809 8192000000 10509979776 10949836800 1184035776 12.628 100 99 191 1 1611680176 404016368 23628032
06:12:21 5042 8192000000 10550074384 10974015488 1191914096 12.701 100 63 125 0 1657108976 449641168 15553712
06:13:30 4206 8192000000 10568888288 10992939008 1170803088 12.504 100 65 128 0 1675440176 487364160 17272928
06:14:38 4221 8192000000 10588036240 11071774720 1165076096 12.451 100 51 102 0 1704582272 525752256 13753920
06:15:51 10984 8192000000 10524849632 11038662656 1169346320 12.491 100 45 87 0 1647266704 465896544 12023840
06:17:12 20706 8192000000 10408474096 10889977856 1153939472 12.346 99 43 82 1 1543202720 378342800 10920448
06:18:34 30572 8192000000 10298779184 10812764160 1159911680 12.402 100 27 54 1 1440273760 273712864 6649216
06:20:15 45184 8192000000 10087495104 10646642688 1149577264 12.306 100 12 22 1 1270264672 117934432 2752976
06:22:02 13768 8192000000 10145963120 10580246528 1150162112 12.311 100 49 98 0 1225772864 65832128 9778624
06:23:19 30891 8192000000 10109703968 10557558784 1153081584 12.338 100 32 59 1 1215540320 56312960 6145776
06:25:01 25580 8192000000 10145338192 10583044096 1138195872 12.199 99 30 56 0 1223361744 79524768 5641104
06:26:27 74450 8192000000 10094293840 10631458816 1148231888 12.293 100 10 20 1 1251785456 101411648 2141920
06:29:13 69795 8192000000 10080984272 10539216896 1110372224 11.936 96 38 67 1 1155189072 38107824 6709024
06:31:25 37429 8192000000 10086255488 10524209152 1142830960 12.242 100 38 76 0 1178957216 27728512 8397744
06:33:04 49864 8192000000 10135258304 10580463616 1148882624 12.299 99 27 50 0 1244774192 90228544 5663024
06:34:58 31596 8192000000 10168956864 10617393152 1132406528 12.144 99 52 97 1 1251116864 107810880 10899456
06:36:35 140804 8192000000 10136993328 10611589120 1123924784 12.064 98 19 37 0 1239699632 111460480 4314368
06:40:03 53131 8192000000 10127099696 10605871104 1152790160 12.336 100 18 34 0 1252430736 95778720 3861856
06:41:56 143960 8192000000 10053579968 10520117248 1037216448 11.238 90 6 12 0 1068446912 30109296 1121168
06:45:21 76070 8192000000 10098159904 10580340736 1119223360 12.020 99 23 46 0 1175591808 51759568 4608880
06:47:39 45465 8192000000 10140922608 10608623616 1102643072 11.863 96 29 58 0 1199090304 90463488 5983744
06:49:26 50329 8192000000 10052438240 10541662208 1090053792 11.743 96 15 30 0 1172521936 78962880 3505264
06:51:17 26355 8192000000 10119196016 10563694592 1114686736 11.977 98 39 78 0 1184067632 61497120 7883776
06:52:45 38095 8192000000 10088004784 10526363648 1125585440 12.080 99 41 75 3 1172973712 39553680 7834592
06:54:27 36568 8192000000 10107453888 10570907648 1128659120 12.109 99 38 74 1 1202774352 66752400 7362832
06:56:11 126586 8192000000 10086525744 10593013760 1100284752 11.840 96 34 64 0 1182751792 76461376 6005664
06:59:20 63464 8192000000 10101243120 10620067840 1120671584 12.033 99 21 42 1 1212540448 87374624 4494240
07:01:24 69664 8192000000 10075504960 10555351040 1109862368 11.931 98 22 44 0 1183160592 68529072 4769152
07:03:35 28357 8192000000 10082654944 10543448064 1118692752 12.015 98 10 20 0 1177427440 56621616 2113072
07:05:06 50606 8192000000 10077721312 10530992128 1044270752 11.306 93 12 24 0 1124579856 77045952 3263152
07:06:58 6840 8192000000 10093311552 10523557888 1122057616 12.046 100 54 101 1 1187944944 54436816 11450512
07:08:06 44329 8192000000 10136277936 10595229696 1135721280 12.175 100 30 60 0 1238828656 96770240 6337136
07:09:52 62082 8192000000 10076092208 10556760064 1086240096 11.707 96 18 36 0 1159862704 69584112 4038496
07:11:55 40164 8192000000 10127371408 10563043328 1101110064 11.848 98 47 92 0 1173073696 62953072 9010560
07:13:38 42013 8192000000 10088720720 10561187840 1098000544 11.819 98 23 46 0 1180547952 77970384 4577024
07:15:25 29600 8192000000 10133432816 10594557952 1122534576 12.051 100 46 92 0 1208456352 76630352 9291424
07:16:54 92390 8192000000 10063109744 10542858240 1134259312 12.161 100 20 40 0 1181763776 43648944 3855520
07:19:27 12068 8192000000 10087935984 10518687744 1113244016 11.963 97 55 108 1 1169457888 45585568 10628304
07:20:40 73064 8192000000 10129476704 10599800832 1147993328 12.291 100 12 24 0 1227666304 77210288 2462688
07:24:16 33222 8192000000 10082381440 10539237376 1140889664 12.224 100 31 60 0 1187536720 39336544 7310512
07:25:56 22513 8192000000 10120774880 10575998976 1140374496 12.219 99 40 78 0 1216646928 67648464 8623968
07:27:22 89328 8192000000 10153920640 10643472384 1141907552 12.233 99 38 74 1 1248596176 98639952 8048672
07:29:52 50258 8192000000 10150244768 10614915072 1115418768 11.984 97 19 38 1 1209096688 88841168 4836752
07:31:46 60493 8192000000 10085723712 10546098176 1101906720 11.856 96 30 58 0 1182521184 74573184 6041280
07:33:47 62150 8192000000 10111860320 10568380416 1091973488 11.761 96 37 75 0 1147470944 47568688 7928768
07:35:56 37026 8192000000 10101570064 10540793856 1115887504 11.988 98 39 78 2 1174580400 50823184 7869712
07:37:38 116334 8192000000 10087421536 10540355584 1127844736 12.101 99 45 88 0 1185213744 48015792 9353216
07:40:34 47194 8192000000 10072904592 10531528704 1110261552 11.935 98 10 20 0 1156094864 43603248 2230064
07:42:27 55136 8192000000 10067550000 10525028352 1121558016 12.042 99 27 47 1 1169886208 43764400 4563792
07:44:23 87118 8192000000 10036954272 10503856128 1081610672 11.663 95 10 17 0 1116082992 32958272 1514048
07:46:50 19579 8192000000 10095167680 10550964224 1142088848 12.235 100 34 67 0 1195168176 45342000 7737328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-06-2022 07:48:57 to 25-06-2022 07:49:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 25-06-2022 07:49:14 to 25-06-2022 07:49:15.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          222452
MON$OLDEST_ACTIVE               224982
MON$OLDEST_SNAPSHOT             224982
MON$NEXT_TRANSACTION            224982
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-30 07:30:08.6030 GMT
MON$PAGES                       2053536
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2789 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2789 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2789 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2789 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Jun 25 07:47:33 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Jun 25 07:47:34 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Jun 25 07:47:34 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Sat Jun 25 07:47:34 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Sat Jun 25 07:47:34 2022
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:25: 
:26: 
:27: mscs-test-161.budzdorov.ru    Sat Jun 25 07:47:34 2022
:28:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:29: 
:30: 
:31: mscs-test-161.budzdorov.ru    Sat Jun 25 07:47:34 2022
:32:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:33: 
:34: 
:35: mscs-test-161.budzdorov.ru    Sat Jun 25 07:47:34 2022
:36:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:37: 
:38: 
:39: mscs-test-161.budzdorov.ru    Sat Jun 25 07:47:35 2022
:40:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:41: 
--- end of diff output ---

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

25.06.22 07:49:17. There are 3 logs in /var/tmp/logs-oltp40 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-25 07:49:19 No severe PSQL-related problems occured
25.06.22 07:49:20. End of report.