Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.04.2022 13:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16462148 kB
MemAvailable 31611296 kB
Buffers 0 kB
Cached 15282304 kB
SwapCached 2232 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-04-04 13:37:15
DTS_END                         2022-04-04 13:37:15.6620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38738
TRN_ID                          218415
ATT_ID                          614
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: 9075.95 23507 1089114
customer order: creation 174.09 497 20891
customer order: refuse 13.88 443 1665
order to supplier: creation 44.83 1512 5380
order to supplier: removal 3.66 160919 439
invoice (draft): creation 45.55 3300 5466
invoice (draft): removal 51.50 1007 6180
invoice accept: apply 42.14 26782 5057
invoice accept: cancel 46.48 1318 5578
customer reserve: creation 3853.12 259 462374
customer reserve: removal 4501.93 102 540231
realization accept: apply 82.63 321 9915
realization accept: cancel 86.58 179 10389
payment from customer: creation 59.84 204 7181
payment from customer: removal 5.26 164 631
payment to supplier: creation 48.16 226 5779
payment to supplier: removal 3.63 133 436
service: total inventory turnovers 3.98 4258 477
service: total monetary turnovers 7.71 384 925
service: aggregate perf. data 0.94 17240 113
service: refresh index statistics 0.06 250898 7

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-04-2022 13:40:00 to 04-04-2022 13:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9512
TEST_TIME 2 8257
TEST_TIME 3 6636
TEST_TIME 4 6360
TEST_TIME 5 6301
TEST_TIME 6 6150
TEST_TIME 7 6412
TEST_TIME 8 6538
TEST_TIME 9 6353
TEST_TIME 10 6229
TEST_TIME 11 6242
TEST_TIME 12 6327
TEST_TIME 13 6532
TEST_TIME 14 6552
TEST_TIME 15 6667
TEST_TIME 16 6864
TEST_TIME 17 6827
TEST_TIME 18 6820
TEST_TIME 19 6913
TEST_TIME 20 7211
TEST_TIME 21 7496
TEST_TIME 22 7432
TEST_TIME 23 7463
TEST_TIME 24 7649
TEST_TIME 25 8059
TEST_TIME 26 8179
TEST_TIME 27 8382
TEST_TIME 28 8637
TEST_TIME 29 8563
TEST_TIME 30 8474
TEST_TIME 31 8891
TEST_TIME 32 8946
TEST_TIME 33 8771
TEST_TIME 34 9007
TEST_TIME 35 10320
TEST_TIME 36 10245
TEST_TIME 37 10415
TEST_TIME 38 10242
TEST_TIME 39 10121
TEST_TIME 40 10088
TEST_TIME 41 10155
TEST_TIME 42 10059
TEST_TIME 43 10021
TEST_TIME 44 9889
TEST_TIME 45 9679
TEST_TIME 46 9730
TEST_TIME 47 9691
TEST_TIME 48 9573
TEST_TIME 49 9538
TEST_TIME 50 9452
TEST_TIME 51 9534
TEST_TIME 52 9497
TEST_TIME 53 9534
TEST_TIME 54 9460
TEST_TIME 55 9433
TEST_TIME 56 9351
TEST_TIME 57 9333
TEST_TIME 58 9267
TEST_TIME 59 9193
TEST_TIME 60 9159
TEST_TIME 61 9124
TEST_TIME 62 9104
TEST_TIME 63 9127
TEST_TIME 64 9157
TEST_TIME 65 9208
TEST_TIME 66 9192
TEST_TIME 67 9212
TEST_TIME 68 9182
TEST_TIME 69 9223
TEST_TIME 70 9219
TEST_TIME 71 9328
TEST_TIME 72 9415
TEST_TIME 73 9518
TEST_TIME 74 9437
TEST_TIME 75 9390
TEST_TIME 76 9365
TEST_TIME 77 9308
TEST_TIME 78 9257
TEST_TIME 79 9260
TEST_TIME 80 9191
TEST_TIME 81 9149
TEST_TIME 82 9120
TEST_TIME 83 9062
TEST_TIME 84 9130
TEST_TIME 85 9139
TEST_TIME 86 9115
TEST_TIME 87 9109
TEST_TIME 88 9083
TEST_TIME 89 9071
TEST_TIME 90 9179
TEST_TIME 91 9223
TEST_TIME 92 9191
TEST_TIME 93 9201
TEST_TIME 94 9178
TEST_TIME 95 9257
TEST_TIME 96 9241
TEST_TIME 97 9165
TEST_TIME 98 9112
TEST_TIME 99 9158
TEST_TIME 100 9142
TEST_TIME 101 9065
TEST_TIME 102 9024
TEST_TIME 103 9041
TEST_TIME 104 9074
TEST_TIME 105 9051
TEST_TIME 106 9034
TEST_TIME 107 9048
TEST_TIME 108 9081
TEST_TIME 109 9077
TEST_TIME 110 9052
TEST_TIME 111 9026
TEST_TIME 112 9021
TEST_TIME 113 9046
TEST_TIME 114 9052
TEST_TIME 115 9258
TEST_TIME 116 9243
TEST_TIME 117 9192
TEST_TIME 118 9196
TEST_TIME 119 9182
TEST_TIME 120 9106

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-04-2022 13:40:04 to 04-04-2022 13:40:07.

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 1515445 1515445 0 0.00 0 20291 15 0 0
doc_list_biud 1515431 1515431 0 0.00 0 33399 42 0 0
sp_add_invoice_to_stock 5218 5057 161 3.08 57 200046 26783 0 161
sp_cancel_adding_invoice 5578 5578 0 0.00 6 126916 1318 0 0
sp_cancel_client_order 1665 1665 0 0.00 6 10027 443 0 0
sp_cancel_customer_reserve 540231 540231 0 0.00 1 33111 102 0 0
sp_cancel_pay_from_customer 631 631 0 0.00 3 8612 165 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 6133 133 0 0
sp_cancel_supplier_invoice 6190 6180 10 0.16 3 27181 1007 0 10
sp_cancel_supplier_order 439 439 0 0.00 11 1467339 160919 0 0
sp_cancel_write_off 10389 10389 0 0.00 2 12043 180 0 0
sp_client_order 20891 20891 0 0.00 6 27595 497 0 0
sp_customer_reserve 462374 462374 0 0.00 1 91754 259 0 0
sp_fill_shopping_cart 31727 31727 0 0.00 1 18432 83 0 0
sp_fill_shopping_cart_clo_res 462356 462356 0 0.00 0 19649 19 0 0
sp_get_clo_for_invoice 5011 5011 0 0.00 0 18190 254 0 0
sp_kill_cost_storno 17034 17034 0 0.00 0 7287 79 0 0
sp_kill_qstorno_ret_qs2qd 1097470 1097470 0 0.00 0 33102 45 0 0
sp_kill_qty_storno 579404 579404 0 0.00 0 33398 103 0 0
sp_lock_selected_doc 20539 20539 0 0.00 0 10669 9 0 0
sp_make_cost_storno 27882 27882 0 0.00 0 20243 92 0 0
sp_multiply_rows_for_pdistr 27880 27880 0 0.00 0 10350 43 0 0
sp_multiply_rows_for_qdistr 20891 20891 0 0.00 0 20655 180 0 0
sp_pay_from_customer 7181 7181 0 0.00 6 16081 205 0 0
sp_pay_to_supplier 5779 5779 0 0.00 7 25256 226 0 0
sp_payment_common 12956 12956 0 0.00 2 8040 51 0 0
sp_qd_handle_on_cancel_clo 1665 1665 0 0.00 1 7881 358 0 0
sp_qd_handle_on_invoice_upd_sts 10585 10585 0 0.00 0 33398 545 0 0
sp_qd_handle_on_reserve_upd_sts 9915 9915 0 0.00 0 11981 69 0 0
sp_reserve_write_off 9915 9915 0 0.00 9 20315 321 0 0
sp_supplier_invoice 5466 5466 0 0.00 8 103344 3301 0 0
sp_supplier_order 5380 5380 0 0.00 7 83824 1512 0 0
srv_aggregate_perf_data 113 113 0 0.00 2656 256342 17240 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 723 90056 28664 0 0
srv_find_qd_qs_mism 1040415 1040415 0 0.00 0 17643 11 0 0
srv_make_invnt_saldo 477 477 0 0.00 77 70391 4259 0 0
srv_make_money_saldo 925 925 0 0.00 2 15143 384 0 0
srv_recalc_idx_stat 7 7 0 0.00 48216 542079 250899 0 0
t$perf-abend:sp_add_invoice_to_stock 161 161 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 5067 5067 0 0.00 0 22531 933 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 2 7724 497 0 0
t$perf-norm:sp_cancel_client_order 1665 1665 0 0.00 0 4779 32 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 1425 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 631 631 0 0.00 0 6859 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 8595 32 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 796 39 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 103691 7161 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 8355 25 0 0
t$perf-norm:sp_client_order 20895 20895 0 0.00 0 12912 40 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 689 14 0 0
t$perf-norm:sp_pay_from_customer 7181 7181 0 0.00 0 3385 19 0 0
t$perf-norm:sp_pay_to_supplier 5779 5779 0 0.00 0 6851 24 0 0
t$perf-norm:sp_reserve_write_off 9915 9915 0 0.00 0 7546 23 0 0
t$perf-norm:sp_supplier_invoice 5467 5467 0 0.00 0 7622 35 0 0
t$perf-norm:sp_supplier_order 5380 5380 0 0.00 0 4926 35 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 662 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 5096 77 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 1561 19 0 0
t$perf-norm:srv_make_money_saldo 925 925 0 0.00 0 6123 11 0 0
t$perf-norm:srv_recalc_idx_stat 7 7 0 0.00 3 184 55 0 0
v_add_invoice_to_stock 10327 10006 321 3.10 0 14133 33 0 321
v_all_customers 38338 38338 0 0.00 0 182 4 0 0
v_all_suppliers 30158 30158 0 0.00 0 74 4 0 0
v_all_wares 41778 41778 0 0.00 0 61 4 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 8313 32 0 0
v_cancel_client_order 3330 3330 0 0.00 0 3441 13 0 0
v_cancel_customer_prepayment 1262 1262 0 0.00 0 3227 18 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 1 2423 13 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 2245 13 0 0
v_cancel_supplier_invoice 826 806 20 2.42 0 884 22 0 20
v_cancel_supplier_order 878 878 0 0.00 0 3803 15 0 0
v_cancel_write_off 1962 1962 0 0.00 0 11855 30 0 0
v_max_id_clo_ord 5379 5379 0 0.00 0 3523 6 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 2653 16 0 0
v_max_id_ord_sup 5459 5459 0 0.00 0 3822 7 0 0
v_max_non_paid_invoice 1537 1537 0 0.00 0 96 7 0 0
v_max_non_paid_realizn 3643 3643 0 0.00 0 4700 8 0 0
v_min_id_clo_ord 5379 5379 0 0.00 0 3565 10 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 6756 17 0 0
v_min_id_ord_sup 5459 5459 0 0.00 0 7696 15 0 0
v_min_non_paid_invoice 5778 5778 0 0.00 0 7957 17 0 0
v_min_non_paid_realizn 7178 7178 0 0.00 0 8032 25 0 0
v_our_firm 10510 10510 0 0.00 0 66 5 0 0
v_reserve_write_off 19826 19826 0 0.00 0 14155 16 0 0
x_lock_depdocs_on_canc_invoice 11355 11355 0 0.00 0 15186 117 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 6090 331 0 0
x_make_qstorno_1000_1200 5380 5380 0 0.00 3 83793 1339 0 0
x_make_qstorno_1200_2000 5466 5466 0 0.00 4 95173 3071 0 0
x_make_qstorno_2100_3300 462374 462374 0 0.00 0 91738 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-04-2022 13:40:08 to 04-04-2022 13:40:12.

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:16 27 8192000000 8802055616 8814714880 6870928 0.083 3 1 2 0 6972160 65456 35776
11:08:16 16337 8192000000 9705683968 10000916480 781667600 8.710 100 85 170 0 810355872 7200080 21488192
11:09:35 22186 8192000000 9743937728 10047545344 821448176 9.113 100 92 184 1 855004864 11381600 22175088
11:11:02 23535 8192000000 9775592272 10089971712 841075216 9.311 100 88 178 1 883719264 21533504 21110544
11:12:29 18465 8192000000 9848246896 10176131072 899210672 9.890 100 74 146 0 951164032 36352720 15600640
11:13:51 7684 8192000000 9890919616 10233978880 946262384 10.354 100 75 144 0 999129472 37260096 15606992
11:15:01 3722 8192000000 9958979328 10306592768 981063008 10.695 100 91 182 0 1068006304 68346160 18597136
11:16:07 1290 8192000000 10000373728 10358042624 1007475344 10.951 100 97 190 1 1121536368 94362016 19699008
11:17:12 2183 8192000000 10004726000 10371551232 1024236384 11.113 100 90 178 3 1121210384 79670688 17303312
11:18:17 1110 8192000000 10017956496 10389811200 1048406560 11.345 100 93 173 2 1146408928 79752304 18250064
11:19:20 905 8192000000 10061319568 10436005888 1060111408 11.458 100 96 185 2 1179168384 100859040 18197936
11:20:22 1204 8192000000 10033814064 10408243200 1068051776 11.533 100 92 170 2 1152773248 65329968 19391504
11:21:25 4204 8192000000 10034544400 10409115648 1072534816 11.576 100 86 165 0 1148576864 61349312 14692736
11:22:30 11151 8192000000 10038965136 10423058432 1076655488 11.616 100 76 149 0 1166449760 75399712 14394560
11:23:42 2502 8192000000 10029345872 10417274880 1083918448 11.685 100 72 130 1 1148765440 51717056 13129936
11:24:46 1183 8192000000 10091945856 10483585024 1089180144 11.735 100 91 178 3 1213634384 107094624 17359616
11:25:48 1264 8192000000 10061921328 10450874368 1093962464 11.780 100 93 179 1 1181270432 70504288 16803680
11:26:51 968 8192000000 10066123056 10452471808 1102183760 11.858 100 96 183 2 1187666336 66838128 18644448
11:27:53 1357 8192000000 10067727184 10456821760 1105139872 11.886 100 93 172 2 1192468432 68521088 18807472
11:28:55 4820 8192000000 10063715152 10457251840 1100771824 11.845 100 69 136 1 1177973456 63555568 13646064
11:30:02 2339 8192000000 10058122192 10449739776 1101791248 11.855 100 78 147 3 1192181168 75765120 14624800
11:31:06 2469 8192000000 10048180512 10443116544 1078991136 11.638 99 61 118 1 1173315504 82272752 12051616
11:32:10 1404 8192000000 10021345440 10402852864 1064356064 11.498 100 93 181 0 1145687104 62669984 18661056
11:33:13 1422 8192000000 10027372208 10407837696 1072946480 11.580 100 96 191 1 1146825984 54385696 19493808
11:34:16 1365 8192000000 10001227440 10374295552 1043112560 11.295 100 96 183 0 1123665104 61832544 18720000
11:35:18 1117 8192000000 9992838672 10362417152 1034901248 11.216 98 92 171 0 1115841776 61500752 19439776
11:36:20 912 8192000000 9961489648 10336837632 1026921520 11.139 99 79 151 0 1084015776 42357568 14736688
11:37:23 3336 8192000000 9996677024 10374774784 1048027984 11.342 100 88 173 1 1122150112 56419296 17702832
11:38:38 2162 8192000000 10017837056 10397237248 1053743872 11.397 100 85 161 3 1135319520 65856080 15719568
11:39:47 10216 8192000000 10012127936 10396577792 1064943456 11.504 100 67 127 0 1135817136 58069232 12804448
11:41:03 15936 8192000000 10045784288 10451853312 1069461344 11.547 100 49 94 3 1160506528 81027632 10017552
11:42:21 8694 8192000000 10061819424 10463137792 1088905040 11.732 100 65 123 1 1181512016 78313504 14293472
11:43:38 4882 8192000000 10106165584 10507431936 1097621328 11.815 100 63 120 0 1215420656 105327840 12471488
11:44:46 955 8192000000 10150641520 10541539328 1114099216 11.971 100 94 180 0 1262439168 129419520 18920432
11:45:57 1179 8192000000 10137155984 10534846464 1121215440 12.038 100 79 146 0 1253582736 117541888 14825408
11:47:04 3980 8192000000 10168400208 10556649472 1131862864 12.139 100 86 166 1 1283756240 134123056 17770320
11:48:16 2862 8192000000 10191545568 10586628096 1126945232 12.093 99 82 155 1 1306775312 163155856 16674224
11:49:24 914 8192000000 10245850288 10648584192 1153340320 12.341 100 97 186 0 1360082272 185515040 21226912
11:50:29 1171 8192000000 10286471728 10694819840 1165866688 12.458 100 91 179 1 1391667888 205840288 19960912
11:51:33 1255 8192000000 10323819568 10728964096 1177812576 12.570 100 96 191 2 1437702768 238203792 21686400
11:52:37 1150 8192000000 10354300608 10764914688 1188702848 12.671 100 92 180 1 1464218400 255126352 20389200
11:53:42 1324 8192000000 10371037664 10790268928 1190910160 12.692 100 82 161 1 1481415968 271329072 19176736
11:54:49 824 8192000000 10422208128 10826022912 1191756000 12.700 100 94 183 1 1528190144 316054304 20379840
11:55:54 922 8192000000 10442143104 10864037888 1197579392 12.754 100 93 182 0 1546586112 327574880 21431840
11:56:59 1025 8192000000 10457883600 10892681216 1191305360 12.696 100 98 194 1 1567692064 353429120 22957584
11:58:04 881 8192000000 10435534400 10882097152 1193392656 12.715 100 98 191 0 1542310144 326998256 21919232
11:59:07 716 8192000000 10483288144 10890424320 1198234256 12.760 100 98 195 0 1588347152 364803424 25309472
12:00:13 838 8192000000 10509511840 10925355008 1183244832 12.620 100 93 182 1 1614450080 410141600 21063648
12:01:16 1668 8192000000 10493084640 10935377920 1174666896 12.540 100 96 187 0 1595943504 399166176 22110432
12:02:22 1161 8192000000 10516104704 10947387392 1172751056 12.523 100 99 197 0 1620275744 424128576 23396112
12:03:26 11163 8192000000 10476026592 10944770048 1180507488 12.595 100 47 92 0 1589158032 396607328 12043216
12:04:42 14794 8192000000 10476249456 10927353856 1177774688 12.569 100 48 96 0 1585991632 396370048 11846896
12:06:05 4982 8192000000 10433774928 10870542336 1174983872 12.543 99 48 88 3 1539003216 353714224 10305120
12:07:25 8754 8192000000 10486751824 10929532928 1178606976 12.577 100 39 76 1 1592753424 404224544 9921904
12:08:51 26857 8192000000 10420808240 10866561024 1171824784 12.514 100 38 75 1 1543113712 362501760 8787168
12:10:37 11732 8192000000 10425926864 10866077696 1139490848 12.211 99 32 64 1 1531023536 383429296 8103392
12:11:56 29050 8192000000 10174641072 10763784192 1114713008 11.977 99 26 52 1 1340105680 218441728 6950944
12:13:26 53870 8192000000 10091213872 10554916864 1130512112 12.126 100 23 44 1 1195363344 59771680 5079552
12:15:21 24348 8192000000 10050988080 10476249088 1107664624 11.910 98 50 95 2 1131419856 13689088 10066144
12:16:54 29444 8192000000 10101286672 10559737856 1113930864 11.970 98 36 70 1 1200025216 78338256 7756096
12:18:47 51331 8192000000 10062736480 10489733120 1124494112 12.069 99 32 58 4 1146449072 14634800 7320160
12:20:40 41181 8192000000 10083418720 10529443840 1131049424 12.131 99 44 78 1 1177007600 36825808 9132368
12:22:41 57999 8192000000 10120219600 10568818688 1130525472 12.126 99 47 80 1 1216933584 76559072 9849040
12:24:40 19666 8192000000 10095423408 10570498048 1146520624 12.277 100 25 50 0 1199324784 47742208 5061952
12:26:02 10580 8192000000 10100587040 10533052416 1136986080 12.187 100 54 100 2 1193290656 46160464 10144112
12:27:17 19433 8192000000 10126325856 10565500928 1117219056 12.001 99 45 87 3 1206901376 79975296 9707024
12:28:40 11071 8192000000 10112889824 10558541824 1133616176 12.155 100 57 106 4 1208705792 64188704 10900912
12:30:08 63895 8192000000 10080247888 10572353536 1142470688 12.239 100 25 50 1 1215437328 67241648 5724992
12:32:13 69072 8192000000 10099944960 10563194880 1118465872 12.012 98 15 30 1 1172544912 50853232 3225808
12:35:18 76959 8192000000 10048420544 10543767552 1115997104 11.989 99 21 42 2 1159215568 38301888 4916576
12:37:37 64531 8192000000 10036795616 10511970304 1101151760 11.849 97 8 16 0 1129695600 26880880 1662960
12:39:42 15854 8192000000 10102301072 10547412992 1129580784 12.117 100 37 73 3 1192030224 55148336 7301104
12:41:08 90055 8192000000 10066132576 10510766080 1082660848 11.673 95 25 48 1 1181123904 92837440 5625616
12:43:39 32931 8192000000 10085753984 10535256064 1115827824 11.988 100 36 72 1 1183152560 60042384 7282352
12:45:13 47673 8192000000 10136619520 10560880640 1105245872 11.887 98 27 54 1 1169355360 58459072 5650416
12:47:04 37603 8192000000 10144234112 10602659840 1096970384 11.809 97 24 48 2 1216646048 114617072 5058592
12:49:01 80786 8192000000 10110145008 10591760384 1105491696 11.890 98 14 28 0 1203066688 94489760 3085232
12:51:24 46077 8192000000 10093359616 10544144384 1120420672 12.031 100 42 84 0 1184725056 55159664 9144720
12:53:10 57118 8192000000 10082429376 10514620416 1128719888 12.109 100 44 85 1 1187346240 49304352 9322000
12:55:17 7103 8192000000 10088542416 10539753472 1137209168 12.189 100 39 78 2 1195857840 50678320 7970352
12:56:27 76482 8192000000 10109066448 10574159872 1109023168 11.923 97 21 42 1 1198665856 84834000 4808688
12:58:50 32760 8192000000 10110380960 10555224064 1124724560 12.072 99 17 34 0 1222459056 93716704 4017792
13:00:28 46759 8192000000 10109238720 10584559616 1120128528 12.028 100 7 14 0 1229190576 107273632 1788416
13:02:59 25732 8192000000 10061246048 10498158592 1131445120 12.135 100 28 56 1 1170349856 33283856 5620880
13:04:28 25019 8192000000 10109568496 10549514240 1115872624 11.988 100 37 73 3 1224960272 101216480 7871168
13:05:58 51290 8192000000 10159514144 10595340288 1125828176 12.082 100 44 88 0 1253087696 118210240 9049280
13:07:50 53197 8192000000 10076581792 10533908480 1104931776 11.884 98 25 49 1 1193969200 83843024 5194400
13:09:52 13918 8192000000 10079998400 10497863680 1097984928 11.819 98 49 97 0 1142184304 34404736 9794640
13:11:09 60689 8192000000 10122343344 10604666880 1101973088 11.856 98 35 70 2 1211417536 101769808 7674640
13:13:14 78617 8192000000 10079698336 10525663232 1087549232 11.719 97 26 51 0 1149935472 56826832 5559408
13:16:08 24491 8192000000 10119908224 10598916096 1128470336 12.107 100 32 64 0 1226518368 91397584 6650448
13:17:35 47319 8192000000 10081007024 10554458112 1135238896 12.171 100 8 16 0 1202835008 65849744 1746368
13:20:41 43600 8192000000 10085483280 10575216640 1137271904 12.190 100 25 47 1 1179833392 37745376 4816112
13:22:38 38385 8192000000 10109539584 10543427584 1117478192 12.003 97 31 60 3 1174307200 51327344 5501664
13:24:18 49413 8192000000 10162933488 10641678336 1155510816 12.361 100 45 89 3 1251462384 85783856 10167712
13:26:19 36111 8192000000 10153952032 10595012608 1160834896 12.411 99 33 66 1 1235046704 66896432 7315376
13:27:58 21713 8192000000 10217418176 10653876224 1164887680 12.449 100 44 88 1 1303067776 128800672 9379424
13:29:35 32401 8192000000 10233003056 10703745024 1146759968 12.279 99 33 66 1 1321562192 167157488 7644736
13:31:11 55626 8192000000 10141000912 10708996096 1144730928 12.260 100 12 24 0 1312139088 164514512 2893648
13:33:15 76436 8192000000 10132973696 10564853760 1150973472 12.319 100 22 41 1 1215123264 59857248 4292544
13:35:32 23184 8192000000 10170257040 10629480448 1133215408 12.152 99 44 87 2 1259821072 117281648 9324016
13:37:00 52323 8192000000 8893244384 9046925312 37398032 0.454 4 4 8 0 69617920 31330544 889344

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 04-04-2022 13:40:14 to 04-04-2022 13:40:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 161 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:02, from 04-04-2022 13:40:31 to 04-04-2022 13:40:33.

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          215176
MON$OLDEST_ACTIVE               218722
MON$OLDEST_SNAPSHOT             218722
MON$NEXT_TRANSACTION            218722
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                       2062688
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2746 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2746 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2746 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2746 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Mon Apr  4 13:37:53 2022
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
--- end of diff output ---

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

04.04.22 13:40:34. 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-04-04 13:40:37 No severe PSQL-related problems occured
04.04.22 13:40:38. End of report.