Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.06.2022 13: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 16133556 kB
MemAvailable 31173508 kB
Buffers 0 kB
Cached 15554932 kB
SwapCached 2504 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-27 13:37:17
DTS_END                         2022-06-27 13:37:17.7020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38786
TRN_ID                          224642
ATT_ID                          657
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: 8897.09 28244 1067651
customer order: creation 167.47 487 20096
customer order: refuse 14.87 430 1784
order to supplier: creation 45.13 1346 5416
order to supplier: removal 3.23 177063 388
invoice (draft): creation 46.87 2925 5624
invoice (draft): removal 48.03 1008 5764
invoice accept: apply 42.37 26404 5084
invoice accept: cancel 43.43 1201 5211
customer reserve: creation 3959.63 251 475155
customer reserve: removal 4233.57 107 508028
realization accept: apply 83.59 285 10031
realization accept: cancel 78.63 182 9435
payment from customer: creation 59.23 174 7108
payment from customer: removal 5.59 138 671
payment to supplier: creation 49.34 190 5921
payment to supplier: removal 3.45 130 414
service: total inventory turnovers 4.39 3759 527
service: total monetary turnovers 7.21 415 865
service: aggregate perf. data 1.04 12992 125
service: refresh index statistics 0.03 335399 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-06-2022 13:38:26 to 27-06-2022 13:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8068
TEST_TIME 2 6636
TEST_TIME 3 6551
TEST_TIME 4 6563
TEST_TIME 5 6139
TEST_TIME 6 6631
TEST_TIME 7 7166
TEST_TIME 8 6884
TEST_TIME 9 6983
TEST_TIME 10 6871
TEST_TIME 11 6791
TEST_TIME 12 6782
TEST_TIME 13 6613
TEST_TIME 14 6547
TEST_TIME 15 6695
TEST_TIME 16 6665
TEST_TIME 17 6827
TEST_TIME 18 6990
TEST_TIME 19 6970
TEST_TIME 20 7099
TEST_TIME 21 7538
TEST_TIME 22 7527
TEST_TIME 23 7524
TEST_TIME 24 7539
TEST_TIME 25 7488
TEST_TIME 26 7942
TEST_TIME 27 8126
TEST_TIME 28 8062
TEST_TIME 29 8567
TEST_TIME 30 9033
TEST_TIME 31 9545
TEST_TIME 32 10117
TEST_TIME 33 10691
TEST_TIME 34 10572
TEST_TIME 35 10399
TEST_TIME 36 10329
TEST_TIME 37 10383
TEST_TIME 38 10282
TEST_TIME 39 10096
TEST_TIME 40 9937
TEST_TIME 41 9855
TEST_TIME 42 9731
TEST_TIME 43 9623
TEST_TIME 44 9608
TEST_TIME 45 9576
TEST_TIME 46 9552
TEST_TIME 47 9497
TEST_TIME 48 9393
TEST_TIME 49 9293
TEST_TIME 50 9267
TEST_TIME 51 9404
TEST_TIME 52 9356
TEST_TIME 53 9701
TEST_TIME 54 9644
TEST_TIME 55 9588
TEST_TIME 56 9517
TEST_TIME 57 9583
TEST_TIME 58 9631
TEST_TIME 59 9689
TEST_TIME 60 9606
TEST_TIME 61 9603
TEST_TIME 62 9722
TEST_TIME 63 9683
TEST_TIME 64 9614
TEST_TIME 65 9471
TEST_TIME 66 9442
TEST_TIME 67 9363
TEST_TIME 68 9363
TEST_TIME 69 9343
TEST_TIME 70 9400
TEST_TIME 71 9329
TEST_TIME 72 9265
TEST_TIME 73 9244
TEST_TIME 74 9239
TEST_TIME 75 9213
TEST_TIME 76 9171
TEST_TIME 77 9215
TEST_TIME 78 9187
TEST_TIME 79 9135
TEST_TIME 80 9119
TEST_TIME 81 9252
TEST_TIME 82 9331
TEST_TIME 83 9343
TEST_TIME 84 9312
TEST_TIME 85 9403
TEST_TIME 86 9333
TEST_TIME 87 9263
TEST_TIME 88 9189
TEST_TIME 89 9281
TEST_TIME 90 9277
TEST_TIME 91 9224
TEST_TIME 92 9185
TEST_TIME 93 9142
TEST_TIME 94 9158
TEST_TIME 95 9152
TEST_TIME 96 9126
TEST_TIME 97 9123
TEST_TIME 98 9123
TEST_TIME 99 9125
TEST_TIME 100 9184
TEST_TIME 101 9154
TEST_TIME 102 9166
TEST_TIME 103 9205
TEST_TIME 104 9181
TEST_TIME 105 9190
TEST_TIME 106 9113
TEST_TIME 107 9132
TEST_TIME 108 9092
TEST_TIME 109 9047
TEST_TIME 110 9010
TEST_TIME 111 8961
TEST_TIME 112 8956
TEST_TIME 113 8947
TEST_TIME 114 8968
TEST_TIME 115 8963
TEST_TIME 116 8984
TEST_TIME 117 8984
TEST_TIME 118 8984
TEST_TIME 119 8960
TEST_TIME 120 8927

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-06-2022 13:38:29 to 27-06-2022 13:38:32.

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 1502750 1502750 0 0.00 0 30125 14 0 0
doc_list_biud 1502744 1502744 0 0.00 0 29880 42 0 0
sp_add_invoice_to_stock 5169 5084 85 1.64 36 220735 26404 0 85
sp_cancel_adding_invoice 5211 5211 0 0.00 6 82646 1202 0 0
sp_cancel_client_order 1784 1784 0 0.00 5 19836 430 0 0
sp_cancel_customer_reserve 508028 508028 0 0.00 1 23682 108 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 4 8995 138 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 4 6069 130 0 0
sp_cancel_supplier_invoice 5767 5764 3 0.05 2 26455 1009 0 3
sp_cancel_supplier_order 388 388 0 0.00 7 1354110 177063 0 0
sp_cancel_write_off 9435 9435 0 0.00 2 10693 182 0 0
sp_client_order 20096 20096 0 0.00 5 38752 487 0 0
sp_customer_reserve 475155 475155 0 0.00 0 55586 252 0 0
sp_fill_shopping_cart 31127 31127 0 0.00 1 13087 76 0 0
sp_fill_shopping_cart_clo_res 475129 475129 0 0.00 0 24990 20 0 0
sp_get_clo_for_invoice 5047 5047 0 0.00 0 41427 327 0 0
sp_kill_cost_storno 15731 15731 0 0.00 0 9235 77 0 0
sp_kill_qstorno_ret_qs2qd 1031643 1031643 0 0.00 0 26449 47 0 0
sp_kill_qty_storno 545685 545685 0 0.00 0 29879 106 0 0
sp_lock_selected_doc 20975 20975 0 0.00 0 10625 8 0 0
sp_make_cost_storno 28106 28106 0 0.00 0 14244 76 0 0
sp_multiply_rows_for_pdistr 28106 28106 0 0.00 0 10410 37 0 0
sp_multiply_rows_for_qdistr 20096 20096 0 0.00 0 9266 178 0 0
sp_pay_from_customer 7108 7108 0 0.00 5 12966 175 0 0
sp_pay_to_supplier 5921 5921 0 0.00 6 15372 191 0 0
sp_payment_common 13027 13027 0 0.00 2 10559 41 0 0
sp_qd_handle_on_cancel_clo 1784 1784 0 0.00 0 13138 338 0 0
sp_qd_handle_on_invoice_upd_sts 10258 10258 0 0.00 0 21663 489 0 0
sp_qd_handle_on_reserve_upd_sts 10028 10028 0 0.00 0 29879 67 0 0
sp_reserve_write_off 10031 10031 0 0.00 8 29922 285 0 0
sp_supplier_invoice 5624 5624 0 0.00 9 61524 2926 0 0
sp_supplier_order 5416 5416 0 0.00 7 26194 1347 0 0
srv_aggregate_perf_data 125 125 0 0.00 2313 138882 12992 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 761 138925 36383 0 0
srv_find_qd_qs_mism 1017169 1017169 0 0.00 0 30124 11 0 0
srv_make_invnt_saldo 527 527 0 0.00 78 72327 3759 0 0
srv_make_money_saldo 865 865 0 0.00 1 17297 416 0 0
srv_recalc_idx_stat 4 4 0 0.00 255329 470335 335400 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5089 5089 0 0.00 1 11877 836 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 1 11480 422 0 0
t$perf-norm:sp_cancel_client_order 1784 1784 0 0.00 0 11161 32 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 3664 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 817 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 4795 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 1021 31 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 90720 6270 0 0
t$perf-norm:sp_cancel_write_off 1078 1078 0 0.00 0 4429 24 0 0
t$perf-norm:sp_client_order 20097 20097 0 0.00 0 7406 34 0 0
t$perf-norm:sp_customer_reserve 999 999 0 0.00 0 4177 26 0 0
t$perf-norm:sp_pay_from_customer 7108 7108 0 0.00 0 13785 24 0 0
t$perf-norm:sp_pay_to_supplier 5921 5921 0 0.00 0 7146 19 0 0
t$perf-norm:sp_reserve_write_off 10031 10031 0 0.00 0 20889 27 0 0
t$perf-norm:sp_supplier_invoice 5624 5624 0 0.00 0 3047 29 0 0
t$perf-norm:sp_supplier_order 5416 5416 0 0.00 0 3074 28 0 0
t$perf-norm:srv_aggregate_perf_data 125 125 0 0.00 0 2813 45 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 307 11 0 0
t$perf-norm:srv_make_invnt_saldo 527 527 0 0.00 0 614 10 0 0
t$perf-norm:srv_make_money_saldo 865 865 0 0.00 0 1283 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 27 207 92 0 0
v_add_invoice_to_stock 10256 10086 170 1.65 0 14676 26 0 170
v_all_customers 37048 37048 0 0.00 0 154 3 0 0
v_all_suppliers 30818 30818 0 0.00 0 104 4 0 0
v_all_wares 40189 40189 0 0.00 0 122 4 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 15335 52 0 0
v_cancel_client_order 3568 3568 0 0.00 0 19707 24 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 896 11 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 3680 15 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 3368 18 0 0
v_cancel_supplier_invoice 872 866 6 0.68 0 3351 25 0 6
v_cancel_supplier_order 776 776 0 0.00 0 415 11 0 0
v_cancel_write_off 2156 2156 0 0.00 0 2579 24 0 0
v_max_id_clo_ord 5412 5412 0 0.00 0 1050 5 0 0
v_max_id_clo_res 999 999 0 0.00 0 1211 9 0 0
v_max_id_ord_sup 5618 5618 0 0.00 0 6226 10 0 0
v_max_non_paid_invoice 1539 1539 0 0.00 0 164 7 0 0
v_max_non_paid_realizn 3597 3597 0 0.00 0 1822 8 0 0
v_min_id_clo_ord 5412 5412 0 0.00 0 1679 8 0 0
v_min_id_clo_res 999 999 0 0.00 0 5928 22 0 0
v_min_id_ord_sup 5618 5618 0 0.00 0 7924 10 0 0
v_min_non_paid_invoice 5920 5920 0 0.00 0 4426 12 0 0
v_min_non_paid_realizn 7107 7107 0 0.00 0 6686 14 0 0
v_our_firm 10150 10150 0 0.00 0 63 4 0 0
v_reserve_write_off 20051 20051 0 0.00 0 17563 15 0 0
x_lock_depdocs_on_canc_invoice 10542 10542 0 0.00 0 22801 126 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 2855 326 0 0
x_make_qstorno_1000_1200 5416 5416 0 0.00 3 26183 1190 0 0
x_make_qstorno_1200_2000 5624 5624 0 0.00 4 59879 2722 0 0
x_make_qstorno_2100_3300 475154 475154 0 0.00 0 50159 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-06-2022 13:38:33 to 27-06-2022 13:38:37.

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
11:07:21 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:23 33809 8192000000 9728596080 10029281280 802832272 8.925 100 89 176 1 831993392 8306832 20854288
11:10:01 15805 8192000000 9771594000 10082131968 848475840 9.385 100 87 173 0 881239120 12518752 20244528
11:11:24 15959 8192000000 9811081776 10132938752 883596000 9.735 100 89 176 0 922422656 19059216 19767440
11:12:43 9684 8192000000 9884320176 10220158976 920812496 10.104 100 79 156 0 993927760 56455728 16659536
11:13:56 3772 8192000000 9928295152 10270322688 953206720 10.423 100 87 167 0 1034881584 64145648 17529216
11:15:03 1722 8192000000 9960327200 10310311936 991924144 10.800 100 92 183 0 1080056848 68831776 19300928
11:16:08 1586 8192000000 10003236720 10365083648 1009841744 10.974 100 96 190 0 1123521360 93982816 19696800
11:17:11 1058 8192000000 10009330640 10373763072 1034121088 11.208 100 97 188 0 1122994240 70468352 18404800
11:18:14 742 8192000000 10032937072 10405330944 1051396608 11.374 100 96 182 1 1152010624 82332576 18281440
11:19:15 990 8192000000 10033915552 10418561024 1058183936 11.439 100 92 182 0 1153267872 76682272 18401664
11:20:18 2730 8192000000 10035432256 10426527744 1065191872 11.506 100 65 130 1 1157245264 79080240 12973152
11:21:24 1089 8192000000 10033884800 10414948352 1075137024 11.601 100 93 174 0 1155955456 61892816 18925616
11:22:27 1161 8192000000 10043092256 10433630208 1079381584 11.642 100 95 188 1 1167472064 68709408 19381072
11:23:29 959 8192000000 10063450384 10452070400 1083925504 11.685 100 88 171 0 1189258880 87690272 17643104
11:24:31 1174 8192000000 10041290512 10425516032 1084162816 11.687 100 95 181 2 1161320816 57746720 19411280
11:25:33 1230 8192000000 10081849392 10468122624 1093796464 11.779 100 98 188 0 1208072592 95830256 18445872
11:26:35 3407 8192000000 10040109648 10427224064 1090289216 11.745 100 87 168 1 1164333632 58257536 15786880
11:27:39 10825 8192000000 10036222032 10442911744 1089274704 11.736 100 49 94 1 1159631152 61146544 9209904
11:28:55 1771 8192000000 10023537648 10409635840 1073637776 11.587 99 76 149 2 1146027424 57648288 14741360
11:29:59 1072 8192000000 10040346672 10428276736 1082469328 11.671 100 88 172 4 1164615584 63426592 18719664
11:31:00 877 8192000000 9979688160 10363662336 1055435328 11.413 99 89 154 1 1105966048 33134048 17396672
11:32:03 3836 8192000000 10012310752 10391396352 1042849648 11.292 99 77 142 3 1122650048 65727552 14072848
11:33:09 1642 8192000000 9992088064 10372055040 1046040816 11.323 100 64 125 0 1116383968 57555616 12787536
11:34:11 1044 8192000000 10015951088 10395992064 1050365040 11.364 100 85 163 0 1134644320 67114592 17164688
11:35:13 810 8192000000 9994214976 10367655936 1020390464 11.076 100 92 185 0 1116517008 76989568 19136976
11:36:15 1737 8192000000 9994353296 10367201280 1034832944 11.215 100 85 161 1 1123401120 72219088 16349088
11:37:23 755 8192000000 10022149520 10400829440 1051058080 11.371 100 88 163 1 1147372656 80057056 16257520
11:38:32 5020 8192000000 10016630032 10402316288 1067660752 11.530 100 65 125 0 1127475280 47305328 12509200
11:39:43 6378 8192000000 10038359584 10422280192 1076121232 11.610 100 74 138 3 1164909168 74224576 14563360
11:40:53 876 8192000000 10058911920 10446467072 1086383648 11.708 100 94 178 1 1184128432 77738832 20005952
11:41:58 4631 8192000000 10078717696 10473897984 1095915040 11.799 100 67 130 1 1197594592 88485680 13193872
11:43:10 2981 8192000000 10107103808 10499842048 1103725088 11.873 100 78 144 2 1221082288 102866320 14490880
11:44:16 1843 8192000000 10113715024 10506719232 1116920656 11.998 100 95 171 1 1229793968 93964992 18908320
11:45:23 10540 8192000000 10139298800 10539225088 1133992784 12.159 100 68 127 1 1243968256 96703120 13272352
11:46:45 5682 8192000000 10141718448 10541522944 1125843168 12.082 98 66 132 1 1250719872 110835152 14041552
11:47:53 5327 8192000000 10168961568 10574016512 1150193024 12.311 100 70 130 1 1281998768 116921456 14884288
11:49:05 1318 8192000000 10230564896 10635231232 1167610496 12.474 100 78 142 6 1345106128 161987616 15508016
11:50:13 5516 8192000000 10249990528 10666520576 1178439504 12.576 100 57 113 0 1359167200 167617232 13110464
11:51:21 1870 8192000000 10301274016 10700652544 1175233552 12.546 99 89 169 2 1405053456 211132544 18687360
11:52:27 1004 8192000000 10344325760 10749046784 1200996944 12.786 100 88 172 2 1452790128 231455728 20337456
11:53:30 1224 8192000000 10396994272 10800578560 1194447712 12.725 100 95 185 2 1505335280 289329200 21558368
11:54:34 944 8192000000 10410827808 10834444288 1202227328 12.797 100 97 187 0 1515859680 290530800 23101552
11:55:38 1508 8192000000 10460936864 10867740672 1202627344 12.801 99 94 187 0 1567792640 342427664 22737632
11:56:42 1521 8192000000 10503233568 10936287232 1212386656 12.891 100 94 181 0 1609733696 373624880 23722160
11:57:46 829 8192000000 10512977840 10948947968 1218064464 12.944 100 97 187 2 1619624016 378117872 23441680
11:58:49 813 8192000000 10524414880 10959364096 1207621408 12.847 100 90 171 0 1628661136 401268528 19771200
11:59:54 1015 8192000000 10570622768 10983501824 1212144624 12.889 100 79 148 1 1670148928 441059440 16944864
12:00:58 5215 8192000000 10623682352 11047620608 1209714016 12.866 100 68 136 0 1724668176 498232320 16721840
12:02:10 2185 8192000000 10638566752 11067817984 1207739776 12.848 99 82 158 2 1738841200 512913968 18187456
12:03:18 4692 8192000000 10597165376 11071832064 1209954864 12.869 100 54 107 1 1702943840 479287040 13701936
12:04:25 9902 8192000000 10611407456 11057750016 1199293008 12.770 100 50 100 1 1722683824 510112096 13278720
12:05:37 19780 8192000000 10616856192 11070869504 1171643392 12.512 99 51 98 1 1736323744 551304336 13376016
12:06:58 17039 8192000000 10491430336 11018723328 1173730768 12.532 100 40 80 0 1599145408 414919808 10494832
12:08:18 16201 8192000000 10401186320 10910584832 1162689392 12.428 100 28 56 0 1514034608 343947904 7397312
12:10:01 89912 8192000000 10064483776 10545680384 1125979872 12.083 99 9 18 0 1216428288 88814480 1633936
12:12:31 23272 8192000000 10082508400 10509336576 1139070288 12.207 100 60 110 0 1172797280 20444064 13282928
12:14:02 29202 8192000000 10073341968 10531205120 1137795296 12.195 100 23 46 0 1190387552 48180448 4411808
12:15:32 135457 8192000000 10065707360 10512232448 1062131888 11.477 93 21 40 0 1093170640 27428000 3610752
12:18:53 99217 8192000000 10079260560 10506780672 1079246480 11.640 95 40 76 2 1119509728 32102992 8160256
12:21:34 4001 8192000000 10146361472 10571239424 1137118640 12.188 100 65 124 0 1240956512 90470368 13367504
12:22:39 36626 8192000000 10135434640 10597818368 1139841376 12.214 100 32 60 1 1256489216 109060368 7587472
12:24:26 58697 8192000000 10192651664 10638262272 1140601504 12.221 99 23 44 0 1271158720 125501616 5055600
12:26:28 34953 8192000000 10227794528 10670120960 1131780784 12.138 98 28 56 0 1315040864 176433280 6826800
12:28:07 52306 8192000000 10184766640 10697170944 1122675280 12.052 99 16 30 0 1330200256 203490112 4034864
12:30:09 138925 8192000000 10063917648 10518515712 1072521152 11.576 95 16 30 0 1120355504 45294352 2540000
12:33:31 6451 8192000000 10122447936 10549047296 1141076096 12.226 100 57 113 0 1213885216 61587728 11221392
12:34:41 55357 8192000000 10104028928 10622791680 1137985760 12.197 100 31 62 0 1207620800 63608624 6026416
12:36:39 47173 8192000000 10088593264 10518568960 1143527072 12.249 100 39 77 0 1180317792 28977488 7813232
12:38:47 54326 8192000000 10112283840 10619584512 1136470896 12.182 100 30 60 0 1245200320 102566016 6163408
12:40:42 46109 8192000000 10094190272 10550542336 1085471616 11.700 96 26 49 1 1153825232 63729104 4624512
12:42:34 70603 8192000000 10098771728 10564034560 1118368608 12.012 99 43 84 0 1186807168 58778240 9660320
12:44:49 64816 8192000000 10118381952 10606264320 1122699952 12.052 100 36 70 0 1246297744 115356816 8240976
12:47:14 51283 8192000000 10035599968 10502606848 1098265120 11.821 98 19 35 0 1122732160 20929360 3537680
12:49:09 52570 8192000000 10053660672 10512142336 1120254368 12.029 99 15 30 0 1153230912 30270208 2706336
12:51:17 61560 8192000000 10056875856 10516156416 1110903920 11.941 97 24 48 0 1165902960 50185120 4813920
12:53:25 29744 8192000000 10128215152 10551689216 1117082176 11.999 98 60 116 0 1207745744 77771312 12892256
12:55:00 105683 8192000000 10179388336 10609303552 1100005568 11.838 95 45 84 1 1199518544 90804512 8708464
12:57:46 32722 8192000000 10199852400 10689404928 1139838512 12.214 100 49 98 0 1319372960 168962608 10571840
12:59:25 58762 8192000000 10072034208 10498977792 1117395328 12.002 98 27 54 2 1151135216 28326560 5413328
13:01:24 18120 8192000000 10130114416 10550243328 1110348032 11.936 98 43 86 1 1190194672 71003648 8842992
13:02:50 58903 8192000000 10132646448 10557079552 1104523088 11.881 97 46 91 1 1195501680 81842704 9135888
13:04:52 84624 8192000000 10119974256 10637770752 1134854288 12.167 100 30 59 0 1239473696 97941552 6677856
13:07:24 59598 8192000000 10047483568 10530394112 1107618000 11.910 98 13 26 0 1150736096 40540272 2577824
13:09:28 62248 8192000000 10062390128 10521546752 1080417536 11.651 96 9 18 0 1118717472 36212176 2087760
13:11:34 38060 8192000000 10067992384 10500542464 1096183632 11.801 98 17 34 1 1146296256 46684448 3428176
13:13:16 25429 8192000000 10082012016 10516865024 1111611328 11.948 99 37 69 1 1172163008 52881632 7670048
13:14:48 39860 8192000000 10116595664 10575831040 1133160320 12.151 100 28 56 0 1229042592 89493776 6388496
13:16:31 86608 8192000000 10079789584 10573516800 1135789200 12.176 100 17 32 0 1210210768 70935920 3485648
13:19:01 70549 8192000000 10111777520 10569773056 1114023440 11.970 97 24 48 0 1204468896 85634224 4811232
13:21:13 5302 8192000000 10082876192 10509209600 1130576000 12.127 99 44 86 0 1172043200 32790256 8676944
13:22:19 36227 8192000000 10133630624 10590081024 1138880544 12.205 99 58 116 1 1233573104 83114448 11578112
13:23:56 60864 8192000000 10079840752 10572189696 1129156512 12.113 99 16 32 0 1180027040 47854320 3016208
13:26:03 28078 8192000000 10053435200 10497888256 1135342096 12.172 99 16 25 0 1159089408 20834512 2912800
13:27:42 81594 8192000000 10074895888 10545958912 1100488096 11.842 96 9 18 0 1131504320 28765808 2250416
13:30:03 80342 8192000000 10100930080 10602377216 1094704352 11.787 96 23 46 0 1189477968 89903376 4870240
13:32:33 62899 8192000000 10091109168 10533318656 1112961200 11.960 98 24 47 0 1165804592 48026816 4816576
13:34:37 107283 8192000000 10135994224 10597199872 1134703056 12.166 99 15 31 0 1236038480 97872560 3462864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-06-2022 13:38:39 to 27-06-2022 13:38:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-06-2022 13:38:56 to 27-06-2022 13:38:58.

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          219812
MON$OLDEST_ACTIVE               224755
MON$OLDEST_SNAPSHOT             224755
MON$NEXT_TRANSACTION            224755
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                       2036896
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2790 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2790 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2790 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2790 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 ---
:36: 
:37: mscs-test-161.budzdorov.ru    Mon Jun 27 13:37:21 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Mon Jun 27 13:37:34 2022
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Mon Jun 27 13:37:34 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
--- end of diff output ---

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

27.06.22 13:38:59. 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-27 13:39:01 No severe PSQL-related problems occured
27.06.22 13:39:03. End of report.