Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.04.2022 10: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 16227836 kB
MemAvailable 31512768 kB
Buffers 0 kB
Cached 15505548 kB
SwapCached 2712 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 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-19 10:37:20
DTS_END                         2022-04-19 10:37:20.3010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55650
TRN_ID                          226483
ATT_ID                          592
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: 9161.67 28856 1099401
customer order: creation 176.43 457 21171
customer order: refuse 15.56 374 1867
order to supplier: creation 47.38 1409 5686
order to supplier: removal 3.45 158819 414
invoice (draft): creation 47.58 3261 5710
invoice (draft): removal 48.52 1036 5822
invoice accept: apply 43.37 27737 5204
invoice accept: cancel 44.35 1243 5322
customer reserve: creation 4032.22 264 483866
customer reserve: removal 4397.41 97 527689
realization accept: apply 87.64 299 10517
realization accept: cancel 82.85 176 9942
payment from customer: creation 60.53 163 7264
payment from customer: removal 5.78 121 693
payment to supplier: creation 51.19 197 6143
payment to supplier: removal 3.79 127 455
service: total inventory turnovers 4.59 3566 551
service: total monetary turnovers 8.13 342 975
service: aggregate perf. data 0.88 17639 105
service: refresh index statistics 0.04 359792 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-04-2022 10:39:12 to 19-04-2022 10:39:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10906
TEST_TIME 2 7285
TEST_TIME 3 6166
TEST_TIME 4 5681
TEST_TIME 5 6028
TEST_TIME 6 5996
TEST_TIME 7 6047
TEST_TIME 8 6479
TEST_TIME 9 6489
TEST_TIME 10 7049
TEST_TIME 11 7186
TEST_TIME 12 7288
TEST_TIME 13 7307
TEST_TIME 14 7395
TEST_TIME 15 7422
TEST_TIME 16 7567
TEST_TIME 17 7679
TEST_TIME 18 7670
TEST_TIME 19 7787
TEST_TIME 20 7867
TEST_TIME 21 7826
TEST_TIME 22 7967
TEST_TIME 23 7976
TEST_TIME 24 7990
TEST_TIME 25 8161
TEST_TIME 26 8139
TEST_TIME 27 8538
TEST_TIME 28 8938
TEST_TIME 29 8920
TEST_TIME 30 9515
TEST_TIME 31 10167
TEST_TIME 32 10476
TEST_TIME 33 10662
TEST_TIME 34 10787
TEST_TIME 35 11248
TEST_TIME 36 11262
TEST_TIME 37 11165
TEST_TIME 38 11058
TEST_TIME 39 11246
TEST_TIME 40 11020
TEST_TIME 41 10881
TEST_TIME 42 10803
TEST_TIME 43 10671
TEST_TIME 44 10768
TEST_TIME 45 10625
TEST_TIME 46 10515
TEST_TIME 47 10345
TEST_TIME 48 10260
TEST_TIME 49 10209
TEST_TIME 50 10155
TEST_TIME 51 10399
TEST_TIME 52 10412
TEST_TIME 53 10332
TEST_TIME 54 10250
TEST_TIME 55 10160
TEST_TIME 56 10024
TEST_TIME 57 9924
TEST_TIME 58 10041
TEST_TIME 59 9981
TEST_TIME 60 9934
TEST_TIME 61 9868
TEST_TIME 62 9860
TEST_TIME 63 9909
TEST_TIME 64 9939
TEST_TIME 65 9885
TEST_TIME 66 9875
TEST_TIME 67 9889
TEST_TIME 68 9865
TEST_TIME 69 9918
TEST_TIME 70 9877
TEST_TIME 71 9803
TEST_TIME 72 9781
TEST_TIME 73 9682
TEST_TIME 74 9630
TEST_TIME 75 9585
TEST_TIME 76 9530
TEST_TIME 77 9516
TEST_TIME 78 9491
TEST_TIME 79 9559
TEST_TIME 80 9498
TEST_TIME 81 9476
TEST_TIME 82 9463
TEST_TIME 83 9506
TEST_TIME 84 9579
TEST_TIME 85 9604
TEST_TIME 86 9568
TEST_TIME 87 9527
TEST_TIME 88 9600
TEST_TIME 89 9595
TEST_TIME 90 9580
TEST_TIME 91 9671
TEST_TIME 92 9677
TEST_TIME 93 9586
TEST_TIME 94 9551
TEST_TIME 95 9480
TEST_TIME 96 9479
TEST_TIME 97 9417
TEST_TIME 98 9374
TEST_TIME 99 9396
TEST_TIME 100 9373
TEST_TIME 101 9332
TEST_TIME 102 9280
TEST_TIME 103 9290
TEST_TIME 104 9269
TEST_TIME 105 9255
TEST_TIME 106 9249
TEST_TIME 107 9235
TEST_TIME 108 9245
TEST_TIME 109 9260
TEST_TIME 110 9245
TEST_TIME 111 9279
TEST_TIME 112 9291
TEST_TIME 113 9323
TEST_TIME 114 9307
TEST_TIME 115 9348
TEST_TIME 116 9321
TEST_TIME 117 9310
TEST_TIME 118 9302
TEST_TIME 119 9256
TEST_TIME 120 9189

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-04-2022 10:39:16 to 19-04-2022 10:39:19.

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 1544467 1544467 0 0.00 0 12694 15 0 0
doc_list_biud 1544464 1544464 0 0.00 0 43389 39 0 0
sp_add_invoice_to_stock 5327 5204 123 2.30 68 205013 27737 0 123
sp_cancel_adding_invoice 5322 5322 0 0.00 7 82388 1244 0 0
sp_cancel_client_order 1867 1867 0 0.00 6 5782 374 0 0
sp_cancel_customer_reserve 527689 527689 0 0.00 1 27433 98 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 4 4916 122 0 0
sp_cancel_pay_to_supplier 455 455 0 0.00 4 2888 127 0 0
sp_cancel_supplier_invoice 5831 5822 9 0.15 4 43398 1037 0 9
sp_cancel_supplier_order 414 414 0 0.00 8 1617615 158820 0 0
sp_cancel_write_off 9942 9942 0 0.00 2 26971 176 0 0
sp_client_order 21171 21171 0 0.00 6 30069 458 0 0
sp_customer_reserve 483866 483866 0 0.00 1 48584 265 0 0
sp_fill_shopping_cart 32567 32567 0 0.00 1 24177 88 0 0
sp_fill_shopping_cart_clo_res 483829 483829 0 0.00 0 25277 17 0 0
sp_get_clo_for_invoice 5162 5162 0 0.00 0 34686 243 0 0
sp_kill_cost_storno 16412 16412 0 0.00 0 9690 73 0 0
sp_kill_qstorno_ret_qs2qd 1071556 1071556 0 0.00 0 43387 42 0 0
sp_kill_qty_storno 566734 566734 0 0.00 0 43389 97 0 0
sp_lock_selected_doc 21762 21762 0 0.00 0 8548 8 0 0
sp_make_cost_storno 29085 29085 0 0.00 0 12375 80 0 0
sp_multiply_rows_for_pdistr 29085 29085 0 0.00 0 8700 37 0 0
sp_multiply_rows_for_qdistr 21171 21171 0 0.00 0 13056 161 0 0
sp_pay_from_customer 7264 7264 0 0.00 5 10442 164 0 0
sp_pay_to_supplier 6143 6143 0 0.00 6 9896 198 0 0
sp_payment_common 13407 13407 0 0.00 2 8810 44 0 0
sp_qd_handle_on_cancel_clo 1867 1867 0 0.00 0 5773 309 0 0
sp_qd_handle_on_invoice_upd_sts 10483 10483 0 0.00 0 29636 525 0 0
sp_qd_handle_on_reserve_upd_sts 10517 10517 0 0.00 0 29302 85 0 0
sp_reserve_write_off 10517 10517 0 0.00 8 29754 299 0 0
sp_supplier_invoice 5710 5710 0 0.00 11 130145 3261 0 0
sp_supplier_order 5686 5686 0 0.00 8 34991 1409 0 0
srv_aggregate_perf_data 105 105 0 0.00 2613 128860 17639 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 786 131744 32239 0 0
srv_find_qd_qs_mism 1048330 1048330 0 0.00 0 6934 12 0 0
srv_make_invnt_saldo 551 551 0 0.00 48 60712 3566 0 0
srv_make_money_saldo 975 975 0 0.00 1 15983 343 0 0
srv_recalc_idx_stat 5 5 0 0.00 193465 468581 359793 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5209 5209 0 0.00 2 24185 1009 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 1 6893 491 0 0
t$perf-norm:sp_cancel_client_order 1867 1867 0 0.00 0 2721 26 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 1824 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 893 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 455 455 0 0.00 0 1225 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 446 446 0 0.00 0 13971 67 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 112734 6610 0 0
t$perf-norm:sp_cancel_write_off 1080 1080 0 0.00 0 3960 23 0 0
t$perf-norm:sp_client_order 21171 21171 0 0.00 0 19334 37 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 611 13 0 0
t$perf-norm:sp_pay_from_customer 7264 7264 0 0.00 0 6384 20 0 0
t$perf-norm:sp_pay_to_supplier 6143 6143 0 0.00 0 9246 22 0 0
t$perf-norm:sp_reserve_write_off 10517 10517 0 0.00 0 14050 26 0 0
t$perf-norm:sp_supplier_invoice 5710 5710 0 0.00 0 9016 35 0 0
t$perf-norm:sp_supplier_order 5686 5686 0 0.00 0 2995 33 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 992 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 1054 25 0 0
t$perf-norm:srv_make_invnt_saldo 551 551 0 0.00 0 2858 25 0 0
t$perf-norm:srv_make_money_saldo 975 975 0 0.00 0 3930 16 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 159 65 0 0
v_add_invoice_to_stock 10562 10316 246 2.32 0 3368 25 0 246
v_all_customers 38882 38882 0 0.00 0 142 4 0 0
v_all_suppliers 31926 31926 0 0.00 0 123 4 0 0
v_all_wares 42342 42342 0 0.00 0 136 4 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 3289 22 0 0
v_cancel_client_order 3734 3734 0 0.00 0 2147 9 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 1585 13 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 2564 16 0 0
v_cancel_payment_to_supplier 910 910 0 0.00 0 1986 19 0 0
v_cancel_supplier_invoice 910 892 18 1.97 0 2726 29 0 18
v_cancel_supplier_order 828 828 0 0.00 0 2558 16 0 0
v_cancel_write_off 2160 2160 0 0.00 0 5760 27 0 0
v_max_id_clo_ord 5686 5686 0 0.00 0 3218 6 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 1729 14 0 0
v_max_id_ord_sup 5710 5710 0 0.00 0 11141 8 0 0
v_max_non_paid_invoice 1576 1576 0 0.00 0 547 8 0 0
v_max_non_paid_realizn 3725 3725 0 0.00 0 200 7 0 0
v_min_id_clo_ord 5686 5686 0 0.00 0 3843 10 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 939 8 0 0
v_min_id_ord_sup 5710 5710 0 0.00 0 3998 7 0 0
v_min_non_paid_invoice 6143 6143 0 0.00 0 1665 12 0 0
v_min_non_paid_realizn 7264 7264 0 0.00 0 8805 14 0 0
v_our_firm 10538 10538 0 0.00 0 85 5 0 0
v_reserve_write_off 21034 21034 0 0.00 0 2451 11 0 0
x_lock_depdocs_on_canc_invoice 10698 10698 0 0.00 0 12282 123 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 7254 381 0 0
x_make_qstorno_1000_1200 5686 5686 0 0.00 3 34960 1209 0 0
x_make_qstorno_1200_2000 5710 5710 0 0.00 5 129726 3042 0 0
x_make_qstorno_2100_3300 483866 483866 0 0.00 0 41100 234 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-04-2022 10:39:20 to 19-04-2022 10:39:24.

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
08:07:24 4 8192000000 8799902048 8811700224 5568240 0.067 2 2 3 0 5702848 70688 63920
08:08:26 25599 8192000000 9727343696 10031878144 807346720 8.971 100 85 170 0 835724080 7581376 20795984
08:09:55 21584 8192000000 9771515024 10083532800 846339376 9.363 100 89 178 0 880265248 12614816 21311056
08:11:20 27493 8192000000 9813587152 10139234304 882860560 9.728 100 81 162 0 920090176 18652656 18576960
08:12:50 15380 8192000000 9893677888 10235305984 942465968 10.317 100 73 146 0 995810640 37549440 15795232
08:14:10 5784 8192000000 9944647440 10294095872 979760624 10.682 100 83 163 0 1061401808 64202864 17438320
08:15:19 1586 8192000000 9979834032 10338537472 1010423248 10.979 100 87 172 0 1096618096 69198736 16996112
08:16:24 956 8192000000 10020853152 10390519808 1031841440 11.186 100 96 189 4 1145449104 94538240 19069424
08:17:27 1320 8192000000 10028069888 10402869248 1047321824 11.335 100 95 189 0 1151787680 84729344 19736512
08:18:31 1140 8192000000 10026460448 10399633408 1051563824 11.376 100 96 188 0 1148047632 77517056 18966752
08:19:43 1110 8192000000 10033530896 10408529920 1061878192 11.474 100 95 185 0 1155165296 74263472 19023632
08:20:46 1367 8192000000 10058071392 10435469312 1067865504 11.532 100 98 192 2 1183734560 96193104 19675952
08:21:48 1229 8192000000 10055618672 10433609728 1070750320 11.559 100 97 183 0 1176134976 86015600 19369056
08:22:51 1160 8192000000 10035833552 10421358592 1077328960 11.622 100 93 172 1 1158109552 63185584 17595008
08:23:54 1106 8192000000 10038468016 10425667584 1081697872 11.664 100 87 167 1 1162392128 63255792 17438464
08:24:56 1186 8192000000 10040212576 10423005184 1085773680 11.702 100 98 188 1 1162050656 57893888 18383088
08:25:57 2122 8192000000 10032846240 10422976512 1085554816 11.700 100 86 160 1 1159326992 57277760 16494416
08:27:05 1506 8192000000 10045586880 10439278592 1087124576 11.715 100 65 121 0 1163330128 64153552 12052000
08:28:07 1561 8192000000 10062329360 10454441984 1090902032 11.751 100 81 158 2 1184327984 78255648 15170304
08:29:09 1164 8192000000 10049674128 10437398528 1082512048 11.671 100 92 175 1 1169689056 67462256 19714752
08:30:11 1238 8192000000 10047659296 10437021696 1082533904 11.672 100 90 175 2 1171041696 70469776 18038016
08:31:13 1220 8192000000 10010991664 10385207296 1038673408 11.252 99 94 182 0 1132265408 75589408 18002592
08:32:16 1088 8192000000 10006921040 10382622720 1026261888 11.132 99 93 173 1 1129328992 85434624 17632480
08:33:17 2654 8192000000 9996123296 10375843840 1040596800 11.270 99 73 133 5 1118837792 63314656 14926336
08:34:20 6782 8192000000 9996811744 10384412672 1038630112 11.251 99 67 131 1 1113047056 60550416 13866528
08:35:29 1160 8192000000 10002338208 10373623808 1032415824 11.192 100 97 186 2 1127491824 75820000 19256000
08:36:31 950 8192000000 9991590896 10367508480 1046624416 11.328 100 86 162 0 1115811632 50613280 18573936
08:37:33 781 8192000000 10007260496 10382741504 1048104256 11.342 100 96 185 0 1127994832 59935920 19954656
08:38:42 2834 8192000000 9998765216 10382913536 1056051808 11.419 100 69 133 2 1122075040 52941424 13081808
08:39:51 7113 8192000000 10030000032 10416267264 1072691536 11.578 100 66 120 3 1155824400 71573776 11559088
08:41:01 3387 8192000000 10048211264 10440261632 1088556240 11.729 100 71 123 1 1171998768 71115296 12327232
08:42:12 4907 8192000000 10090802880 10487549952 1095545616 11.795 100 72 133 1 1210986528 102066000 13374912
08:43:20 2012 8192000000 10141476224 10525290496 1105163328 11.887 100 92 169 0 1250657872 127556288 17938256
08:44:29 6412 8192000000 10151624752 10546180096 1106759760 11.902 99 78 146 3 1268254112 146071728 15422624
08:45:41 1313 8192000000 10175446528 10567741440 1129399056 12.116 100 94 180 1 1294586384 144037792 21149536
08:46:46 1786 8192000000 10253652416 10645790720 1148232192 12.293 100 90 167 1 1368348912 202307296 17809424
08:47:51 2146 8192000000 10223340928 10640805888 1162620016 12.428 100 92 174 2 1339399344 157796112 18983216
08:48:57 815 8192000000 10274145616 10683424768 1168088256 12.479 100 95 185 0 1385387968 197326096 19973616
08:50:02 14236 8192000000 10302376720 10705313792 1174603856 12.540 100 74 141 1 1409310176 218548432 16157888
08:51:20 1323 8192000000 10309725872 10726113280 1177221872 12.564 100 90 176 2 1423285872 226940912 19123088
08:52:24 1825 8192000000 10373909680 10778714112 1185019152 12.637 100 79 158 0 1482683616 279886912 17777552
08:53:30 903 8192000000 10401133312 10809921536 1194552800 12.726 100 97 185 0 1507833872 291205600 22075472
08:54:33 3388 8192000000 10392141088 10802204672 1194133248 12.722 100 85 163 0 1510750624 298320400 18296976
08:55:41 887 8192000000 10441194352 10844991488 1186376480 12.650 100 97 188 1 1545064512 337636240 21051792
08:56:43 878 8192000000 10450397184 10873573376 1185332096 12.640 100 91 177 0 1556957792 350685920 20939776
08:57:47 816 8192000000 10490558944 10893320192 1192632592 12.708 100 96 190 0 1597469120 382146080 22690448
08:58:53 1065 8192000000 10530085376 10947842048 1196066752 12.740 100 95 185 1 1640104576 421578560 22459264
08:59:56 1480 8192000000 10574475520 10980528128 1177637936 12.568 99 91 176 3 1677485840 477350160 22497744
09:01:01 931 8192000000 10597555184 11032797184 1193494080 12.716 100 96 185 1 1702457664 486531248 22432336
09:02:04 944 8192000000 10586271120 11028156416 1171687920 12.513 100 92 176 1 1690496816 497232112 21576784
09:03:08 2745 8192000000 10617034976 11083149312 1184561280 12.633 100 77 152 0 1722389664 518674976 19153408
09:04:14 1171 8192000000 10595287488 11062136832 1171122528 12.507 100 94 185 0 1700045664 505006960 23916176
09:05:17 5612 8192000000 10568621952 11061522432 1176314720 12.556 100 47 94 1 1691606560 502395808 12896032
09:06:29 9489 8192000000 10552220384 10996002816 1169517792 12.492 100 43 86 0 1655009824 474555792 10936240
09:07:41 11825 8192000000 10447294320 10899128320 1170542816 12.502 100 47 92 1 1552266464 370354528 11369120
09:09:02 31882 8192000000 10276064544 10755014656 1120901840 12.036 97 34 68 1 1394361536 265241936 8217760
09:10:40 61921 8192000000 10187975312 10666217472 1092405056 11.766 97 16 32 0 1292476176 196029536 4041584
09:12:59 59892 8192000000 10081050000 10553876480 1124535200 12.070 100 9 18 1 1211610864 84705104 2370560
09:15:09 38335 8192000000 10115660288 10550013952 1106514496 11.899 98 22 44 2 1188057712 76777856 4765360
09:16:49 131743 8192000000 10093065248 10600230912 1125159824 12.076 99 18 34 1 1184163232 55095136 3908272
09:20:47 19147 8192000000 10176316768 10601586688 1146037120 12.272 100 69 132 1 1251642704 91463584 14142000
09:22:10 73770 8192000000 10139166144 10602426368 1140833472 12.223 99 17 34 0 1217806048 73309456 3663120
09:24:30 19185 8192000000 10176876752 10624860160 1150121280 12.311 99 22 44 1 1266014176 110802192 5090704
09:25:52 25912 8192000000 10204159824 10680496128 1156663968 12.372 100 29 58 2 1314132512 150520176 6948368
09:27:18 25499 8192000000 10203342480 10651996160 1138742464 12.204 99 32 62 1 1312888768 166418576 7727728
09:28:51 37573 8192000000 10075009184 10618937344 1134324336 12.162 99 12 24 0 1187004144 50142352 2537456
09:30:29 23917 8192000000 10086171552 10600456192 1150689808 12.316 100 7 14 0 1198452848 46382432 1380608
09:31:53 33574 8192000000 10091550992 10564976640 1124251712 12.067 98 10 20 1 1174472144 48303248 1917184
09:34:31 13083 8192000000 10110702096 10554146816 1120041728 12.027 99 36 69 1 1214535936 86143552 8350656
09:35:59 48367 8192000000 10064311904 10510520320 1122210368 12.048 99 30 60 0 1161027968 33262768 5554832
09:38:01 19613 8192000000 10075695936 10517843968 1125424880 12.078 99 42 82 1 1162335632 28310016 8600736
09:39:22 46913 8192000000 10130666080 10587062272 1121149536 12.038 98 31 62 1 1213675040 85788896 6736608
09:41:22 87172 8192000000 10069670768 10508111872 1099523952 11.833 96 28 56 0 1124915808 19656288 5735568
09:43:56 43071 8192000000 10069581168 10527653888 1112302128 11.954 98 28 56 2 1177203840 59590464 5311248
09:45:57 24778 8192000000 10115131152 10555998208 1135549120 12.174 100 41 82 3 1202300832 59095472 7656240
09:47:26 38330 8192000000 10099473344 10531586048 1093605808 11.777 96 42 80 1 1157588496 56292560 7690128
09:49:08 112009 8192000000 10044323008 10484453376 1111159008 11.943 98 25 50 0 1143372144 27502000 4711136
09:52:01 91495 8192000000 10105189392 10578944000 1133271504 12.152 99 26 51 2 1234808112 96029568 5507040
09:54:33 86693 8192000000 10126887552 10626703360 1098876736 11.827 97 15 30 0 1176170176 73837008 3456432
09:57:01 40000 8192000000 10078683792 10513059840 1097414480 11.813 97 24 48 1 1141782640 38501808 5866352
09:58:42 32217 8192000000 10114454752 10557415424 1127732800 12.100 99 28 56 2 1190616480 56285184 6598496
10:00:14 68796 8192000000 10144659712 10619199488 1138069424 12.197 100 31 62 2 1270717696 125838192 6810080
10:02:23 19798 8192000000 10057159104 10480898048 1119003344 12.018 99 41 69 0 1143864640 18202784 6658512
10:03:45 21896 8192000000 10100255680 10529910784 1132660016 12.146 100 48 96 4 1200270992 58106384 9504592
10:05:08 31175 8192000000 10113869552 10565435392 1128830176 12.110 99 50 100 1 1211035872 71912208 10293488
10:06:53 87704 8192000000 10102299632 10595647488 1112646160 11.957 98 20 38 1 1205860576 89442544 3771872
10:09:58 110835 8192000000 10073975168 10540621824 1108062336 11.914 99 26 52 0 1177571904 63979568 5530000
10:12:54 35810 8192000000 10076843632 10548711424 1108046416 11.914 99 24 46 1 1187238064 74332464 4859184
10:14:32 67577 8192000000 10092116720 10552754176 1121904544 12.045 99 13 26 1 1207361856 82511280 2946032
10:17:44 34686 8192000000 10052379312 10498650112 1111979888 11.951 99 26 51 1 1152828256 35167696 5680672
10:20:36 101065 8192000000 10058697696 10505818112 1081535840 11.662 95 11 22 1 1113843424 30204752 2102832
10:23:17 30942 8192000000 10072573296 10523119616 1103878672 11.874 97 23 46 2 1156887040 48172656 4835712
10:24:49 29531 8192000000 10103344512 10529681408 1080207520 11.649 96 41 78 3 1164656048 76295088 8153440
10:26:19 17163 8192000000 10097139696 10539511808 1105014944 11.885 98 36 72 0 1182229408 70431488 6782976
10:27:43 99507 8192000000 10102322544 10556510208 1093258880 11.774 97 28 56 0 1201453936 101829264 6365792
10:30:29 27713 8192000000 10111720144 10563747840 1127109776 12.094 100 45 90 0 1205487648 68877296 9500576
10:31:58 62281 8192000000 10094289072 10545139712 1110467664 11.937 98 36 72 0 1179353472 61792576 7093232
10:34:05 55058 8192000000 10124327264 10610302976 1102123008 11.858 98 43 86 0 1221562688 110780448 8659232
10:36:09 60245 8192000000 10069883520 10523709440 1120174672 12.029 100 21 42 0 1182178944 56586672 5417600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-04-2022 10:39:26 to 19-04-2022 10:39:42.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-04-2022 10:39:43 to 19-04-2022 10:39:45.

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          225234
MON$OLDEST_ACTIVE               227034
MON$OLDEST_SNAPSHOT             227034
MON$NEXT_TRANSACTION            227034
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                       2075616
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:343: 
:344: mscs-test-161.budzdorov.ru    Tue Apr 19 10:37:24 2022
:345:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:346: 
:347: 
:348: mscs-test-161.budzdorov.ru    Tue Apr 19 10:37:28 2022
:349:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:350: 
:351: 
:352: mscs-test-161.budzdorov.ru    Tue Apr 19 10:37:28 2022
:353:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:354: 
--- end of diff output ---

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

19.04.22 10:39:46. 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-19 10:39:49 No severe PSQL-related problems occured
19.04.22 10:39:50. End of report.