Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.12.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 14417016 kB
MemAvailable 30108920 kB
Buffers 0 kB
Cached 17300600 kB
SwapCached 3220 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.3
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-12-13 13:37:22
DTS_END                         2022-12-13 13:37:22.2330
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/48856
TRN_ID                          221907
ATT_ID                          663
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: 8941.28 23530 1072953
customer order: creation 175.53 468 21063
customer order: refuse 14.81 495 1777
order to supplier: creation 44.64 1395 5357
order to supplier: removal 3.32 169657 398
invoice (draft): creation 46.82 3460 5618
invoice (draft): removal 48.43 1089 5811
invoice accept: apply 42.41 27587 5089
invoice accept: cancel 43.70 1422 5244
customer reserve: creation 3926.56 259 471187
customer reserve: removal 4303.18 101 516382
realization accept: apply 85.53 337 10264
realization accept: cancel 75.36 185 9043
payment from customer: creation 60.11 215 7213
payment from customer: removal 5.81 180 697
payment to supplier: creation 49.15 233 5898
payment to supplier: removal 3.35 156 402
service: total inventory turnovers 3.58 5044 430
service: total monetary turnovers 8.13 402 976
service: aggregate perf. data 0.83 18974 99
service: refresh index statistics 0.04 238938 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-12-2022 13:39:49 to 13-12-2022 13:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10479
TEST_TIME 2 8101
TEST_TIME 3 7083
TEST_TIME 4 6846
TEST_TIME 5 6959
TEST_TIME 6 7081
TEST_TIME 7 6841
TEST_TIME 8 6682
TEST_TIME 9 6719
TEST_TIME 10 6873
TEST_TIME 11 7034
TEST_TIME 12 7160
TEST_TIME 13 7302
TEST_TIME 14 7401
TEST_TIME 15 7435
TEST_TIME 16 7291
TEST_TIME 17 7398
TEST_TIME 18 7354
TEST_TIME 19 7566
TEST_TIME 20 7728
TEST_TIME 21 7750
TEST_TIME 22 7739
TEST_TIME 23 7748
TEST_TIME 24 8096
TEST_TIME 25 8104
TEST_TIME 26 8461
TEST_TIME 27 8343
TEST_TIME 28 8672
TEST_TIME 29 8638
TEST_TIME 30 8715
TEST_TIME 31 8676
TEST_TIME 32 9147
TEST_TIME 33 9403
TEST_TIME 34 9313
TEST_TIME 35 9457
TEST_TIME 36 9558
TEST_TIME 37 9813
TEST_TIME 38 9771
TEST_TIME 39 9996
TEST_TIME 40 10299
TEST_TIME 41 10251
TEST_TIME 42 10234
TEST_TIME 43 10279
TEST_TIME 44 10377
TEST_TIME 45 10293
TEST_TIME 46 10229
TEST_TIME 47 10115
TEST_TIME 48 10176
TEST_TIME 49 10123
TEST_TIME 50 9964
TEST_TIME 51 9965
TEST_TIME 52 9927
TEST_TIME 53 10102
TEST_TIME 54 10045
TEST_TIME 55 9907
TEST_TIME 56 9854
TEST_TIME 57 9721
TEST_TIME 58 9700
TEST_TIME 59 9725
TEST_TIME 60 9675
TEST_TIME 61 9561
TEST_TIME 62 9576
TEST_TIME 63 9529
TEST_TIME 64 9478
TEST_TIME 65 9507
TEST_TIME 66 9488
TEST_TIME 67 9477
TEST_TIME 68 9439
TEST_TIME 69 9431
TEST_TIME 70 9442
TEST_TIME 71 9425
TEST_TIME 72 9362
TEST_TIME 73 9430
TEST_TIME 74 9497
TEST_TIME 75 9482
TEST_TIME 76 9472
TEST_TIME 77 9457
TEST_TIME 78 9454
TEST_TIME 79 9444
TEST_TIME 80 9501
TEST_TIME 81 9464
TEST_TIME 82 9490
TEST_TIME 83 9503
TEST_TIME 84 9520
TEST_TIME 85 9456
TEST_TIME 86 9418
TEST_TIME 87 9434
TEST_TIME 88 9398
TEST_TIME 89 9347
TEST_TIME 90 9356
TEST_TIME 91 9298
TEST_TIME 92 9255
TEST_TIME 93 9267
TEST_TIME 94 9206
TEST_TIME 95 9190
TEST_TIME 96 9153
TEST_TIME 97 9109
TEST_TIME 98 9087
TEST_TIME 99 9082
TEST_TIME 100 9167
TEST_TIME 101 9128
TEST_TIME 102 9115
TEST_TIME 103 9123
TEST_TIME 104 9193
TEST_TIME 105 9187
TEST_TIME 106 9207
TEST_TIME 107 9168
TEST_TIME 108 9202
TEST_TIME 109 9245
TEST_TIME 110 9231
TEST_TIME 111 9299
TEST_TIME 112 9258
TEST_TIME 113 9217
TEST_TIME 114 9165
TEST_TIME 115 9170
TEST_TIME 116 9135
TEST_TIME 117 9101
TEST_TIME 118 9075
TEST_TIME 119 9040
TEST_TIME 120 8974

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-12-2022 13:39:52 to 13-12-2022 13:39:56.

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 1506293 1506293 0 0.00 0 21337 15 0 0
doc_list_biud 1506277 1506277 0 0.00 0 28289 42 0 0
sp_add_invoice_to_stock 5155 5089 66 1.28 57 230885 27587 0 66
sp_cancel_adding_invoice 5244 5244 0 0.00 5 58372 1422 0 0
sp_cancel_client_order 1777 1777 0 0.00 6 10066 496 0 0
sp_cancel_customer_reserve 516382 516382 0 0.00 1 27692 102 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 4 9712 180 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 4 3048 157 0 0
sp_cancel_supplier_invoice 5820 5811 9 0.15 2 19462 1090 0 9
sp_cancel_supplier_order 398 398 0 0.00 13 1485453 169658 0 0
sp_cancel_write_off 9043 9043 0 0.00 2 13887 185 0 0
sp_client_order 21063 21063 0 0.00 5 19843 469 0 0
sp_customer_reserve 471187 471187 0 0.00 1 120916 259 0 0
sp_fill_shopping_cart 32025 32025 0 0.00 1 11609 94 0 0
sp_fill_shopping_cart_clo_res 471152 471152 0 0.00 0 12065 18 0 0
sp_get_clo_for_invoice 5041 5041 0 0.00 0 16877 240 0 0
sp_kill_cost_storno 15386 15386 0 0.00 0 13885 89 0 0
sp_kill_qstorno_ret_qs2qd 1048016 1048016 0 0.00 0 27595 44 0 0
sp_kill_qty_storno 553955 553955 0 0.00 0 28289 104 0 0
sp_lock_selected_doc 21108 21108 0 0.00 0 12784 10 0 0
sp_make_cost_storno 28414 28414 0 0.00 0 21333 106 0 0
sp_multiply_rows_for_pdistr 28412 28412 0 0.00 0 8405 49 0 0
sp_multiply_rows_for_qdistr 21063 21063 0 0.00 0 9950 151 0 0
sp_pay_from_customer 7213 7213 0 0.00 5 23357 215 0 0
sp_pay_to_supplier 5898 5898 0 0.00 6 16927 234 0 0
sp_payment_common 13111 13111 0 0.00 2 20385 62 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 8860 403 0 0
sp_qd_handle_on_invoice_upd_sts 10283 10283 0 0.00 0 28288 595 0 0
sp_qd_handle_on_reserve_upd_sts 10261 10261 0 0.00 0 12252 65 0 0
sp_reserve_write_off 10264 10264 0 0.00 8 29242 338 0 0
sp_supplier_invoice 5618 5618 0 0.00 11 95896 3460 0 0
sp_supplier_order 5357 5357 0 0.00 7 23497 1396 0 0
srv_aggregate_perf_data 99 99 0 0.00 3074 139956 18974 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 765 100492 28936 0 0
srv_find_qd_qs_mism 1023033 1023033 0 0.00 0 20148 11 0 0
srv_make_invnt_saldo 430 430 0 0.00 42 114202 5045 0 0
srv_make_money_saldo 976 976 0 0.00 1 12320 402 0 0
srv_recalc_idx_stat 5 5 0 0.00 53763 373463 238939 0 0
t$perf-abend:sp_add_invoice_to_stock 66 66 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 5095 5095 0 0.00 0 10931 1126 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 1 5664 504 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 5319 36 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 1686 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 1750 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 748 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 1624 43 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 90933 7853 0 0
t$perf-norm:sp_cancel_write_off 1052 1052 0 0.00 0 1316 22 0 0
t$perf-norm:sp_client_order 21063 21063 0 0.00 0 6760 44 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 1364 21 0 0
t$perf-norm:sp_pay_from_customer 7214 7214 0 0.00 0 8434 22 0 0
t$perf-norm:sp_pay_to_supplier 5898 5898 0 0.00 0 3736 21 0 0
t$perf-norm:sp_reserve_write_off 10264 10264 0 0.00 0 10623 27 0 0
t$perf-norm:sp_supplier_invoice 5618 5618 0 0.00 0 3433 38 0 0
t$perf-norm:sp_supplier_order 5357 5357 0 0.00 0 6613 38 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 862 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 433 12 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 5275 38 0 0
t$perf-norm:srv_make_money_saldo 976 976 0 0.00 0 6988 16 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 7 186 84 0 0
v_add_invoice_to_stock 10198 10066 132 1.29 0 15228 31 0 132
v_all_customers 38610 38610 0 0.00 0 81 5 0 0
v_all_suppliers 30420 30420 0 0.00 0 71 5 0 0
v_all_wares 42120 42120 0 0.00 0 200 5 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 3203 19 0 0
v_cancel_client_order 3554 3554 0 0.00 0 4413 13 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 5916 20 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 3236 14 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 330 12 0 0
v_cancel_supplier_invoice 800 782 18 2.25 0 358 24 0 18
v_cancel_supplier_order 796 796 0 0.00 0 3779 23 0 0
v_cancel_write_off 2104 2104 0 0.00 0 5332 30 0 0
v_max_id_clo_ord 5354 5354 0 0.00 0 150 6 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 2438 15 0 0
v_max_id_ord_sup 5610 5610 0 0.00 0 2088 7 0 0
v_max_non_paid_invoice 1652 1652 0 0.00 0 2054 11 0 0
v_max_non_paid_realizn 3693 3693 0 0.00 0 6461 12 0 0
v_min_id_clo_ord 5354 5354 0 0.00 0 1679 9 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 4480 22 0 0
v_min_id_ord_sup 5610 5610 0 0.00 0 9045 15 0 0
v_min_non_paid_invoice 5897 5897 0 0.00 0 8703 25 0 0
v_min_non_paid_realizn 7213 7213 0 0.00 0 20380 29 0 0
v_our_firm 10546 10546 0 0.00 0 62 5 0 0
v_reserve_write_off 20520 20520 0 0.00 0 6172 14 0 0
x_lock_depdocs_on_canc_invoice 10664 10664 0 0.00 0 23884 145 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 6317 402 0 0
x_make_qstorno_1000_1200 5357 5357 0 0.00 3 22641 1204 0 0
x_make_qstorno_1200_2000 5618 5618 0 0.00 5 95586 3202 0 0
x_make_qstorno_2100_3300 471186 471186 0 0.00 0 120914 226 0 0

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

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:26 4 8192000000 8800863472 8812941312 6214912 0.075 4 1 2 0 6317856 67168 35776
11:08:27 26610 8192000000 9714260464 10010902528 789934128 8.794 100 93 186 0 821313104 7677328 23701648
11:09:58 15780 8192000000 9753302352 10059300864 831062576 9.210 100 87 175 0 862119200 10181488 20875136
11:11:19 15195 8192000000 9791751536 10106146816 859544896 9.496 100 85 168 1 895103600 15685360 19873344
11:12:37 10316 8192000000 9829259904 10160996352 898446784 9.883 100 78 154 0 944121872 28640720 17034368
11:13:53 7001 8192000000 9892779920 10233028608 946157152 10.353 100 86 167 1 1008593792 44193440 18243200
11:15:03 2188 8192000000 9960541648 10306781184 984882224 10.732 100 92 177 0 1074981088 71940864 18158000
11:16:09 2006 8192000000 10004061040 10362093568 1014165488 11.016 100 93 184 2 1121675136 88893728 18615920
11:17:13 960 8192000000 9996517664 10365026304 1038773872 11.253 100 95 181 0 1116777920 59634416 18369632
11:18:16 917 8192000000 10028018384 10400641024 1060107328 11.458 100 94 181 3 1148857280 70460704 18289248
11:19:18 1138 8192000000 10050991488 10427764736 1073845984 11.589 100 98 191 3 1171282928 78220160 19216784
11:20:21 1183 8192000000 10068658224 10446548992 1081828048 11.665 100 95 184 2 1191628112 92434688 17365376
11:21:24 2104 8192000000 10062847872 10459672576 1085907136 11.704 100 82 162 1 1186079696 83836736 16335824
11:22:30 7375 8192000000 10059088016 10453024768 1089593808 11.739 100 66 127 1 1168213648 66679488 11940352
11:23:38 1013 8192000000 10030427040 10421772288 1097373600 11.813 100 86 165 2 1152891824 38741232 16776992
11:24:39 2500 8192000000 10059466304 10458791936 1097870320 11.817 100 73 146 2 1181722240 68632880 15219040
11:25:43 5703 8192000000 10039892112 10445217792 1101696848 11.854 100 58 113 3 1154723392 42059728 10966816
11:26:50 2731 8192000000 10070934656 10470383616 1107829088 11.912 100 74 135 0 1196424736 75144832 13450816
11:27:55 1047 8192000000 10076524512 10469826560 1108005024 11.914 100 97 182 0 1196946480 70021328 18920128
11:28:57 1105 8192000000 10070106176 10463195136 1105000480 11.885 100 96 187 2 1192899360 68246880 19652000
11:30:00 831 8192000000 10056556224 10445361152 1094784640 11.788 100 93 178 0 1177945248 65083168 18077440
11:31:02 868 8192000000 10026336704 10408067072 1058272640 11.440 100 89 159 2 1148091056 71530400 18288016
11:32:03 1098 8192000000 10005230176 10383388672 1057601888 11.434 100 94 175 0 1125675888 50182848 17891152
11:33:06 1145 8192000000 10021206528 10398224384 1050295936 11.364 100 89 175 1 1145256576 76893120 18067520
11:34:17 1126 8192000000 10004178736 10379096064 1043178896 11.295 100 88 176 0 1126369488 64936512 18254080
11:35:19 1219 8192000000 10025840368 10400157696 1041573424 11.280 100 94 181 1 1147957728 87073920 19310384
11:36:21 1120 8192000000 9993291504 10362146816 1032886384 11.196 100 96 177 2 1115618720 63164304 19568032
11:37:23 1198 8192000000 10020377696 10392453120 1040933712 11.274 100 95 182 2 1132694672 73077280 18683680
11:38:32 1860 8192000000 10049927312 10431152128 1062811280 11.483 100 80 159 2 1170245056 91929408 15504368
11:39:42 820 8192000000 10043899664 10425171968 1079566128 11.643 100 99 184 2 1164355280 64276544 20512608
11:40:49 2320 8192000000 10042114992 10431762432 1080642160 11.654 100 84 160 0 1163676816 66997520 16037136
11:41:53 7662 8192000000 10090598464 10476441600 1095727792 11.797 100 82 151 0 1205941104 93760256 16453056
11:43:13 1050 8192000000 10090495072 10490081280 1121108176 12.037 100 89 167 2 1210098688 69770768 19219744
11:44:20 1019 8192000000 10119830176 10514251776 1127793008 12.101 100 90 167 0 1235345680 88626848 18925824
11:45:24 2112 8192000000 10150062528 10542891008 1134011888 12.159 100 78 140 1 1262298960 114151696 14135376
11:46:29 2960 8192000000 10189464304 10589356032 1138247488 12.199 100 85 169 0 1303767696 147294128 18226080
11:47:36 1060 8192000000 10213020720 10623930368 1143134448 12.245 100 94 177 2 1317511504 154519776 19857280
11:48:40 983 8192000000 10226410112 10626826240 1148647296 12.297 100 87 163 1 1339956432 173034848 18274288
11:49:43 2270 8192000000 10226175856 10638127104 1142829280 12.242 99 84 156 3 1332548032 169323936 20394816
11:50:48 893 8192000000 10268402928 10678935552 1157404240 12.379 100 93 179 1 1380139424 201824992 20910192
11:51:54 1348 8192000000 10287844416 10696359936 1171785632 12.514 100 80 156 0 1400980512 212252272 16942608
11:52:59 1826 8192000000 10331949328 10733711360 1182594528 12.614 100 90 168 0 1440768192 239156720 19016944
11:54:07 1747 8192000000 10363424992 10779287552 1186471680 12.651 100 96 195 0 1470459712 260806944 23181088
11:55:14 1260 8192000000 10357044240 10777718784 1181867600 12.608 100 91 175 2 1467234368 264550352 20816416
11:56:21 955 8192000000 10389660016 10817597440 1191562288 12.698 100 96 189 0 1495755296 281498592 22694416
11:57:26 1265 8192000000 10401874752 10827657216 1197929008 12.757 100 84 161 0 1513407696 296542064 18936624
11:58:29 1545 8192000000 10407826336 10817966080 1176848208 12.561 100 84 161 0 1516108944 319421552 19839184
11:59:37 756 8192000000 10436520912 10865508352 1188048128 12.665 100 96 190 0 1543552880 332524768 22979984
12:00:42 2165 8192000000 10468307840 10903543808 1193553728 12.716 100 89 174 0 1575872608 361287616 21031264
12:01:48 1161 8192000000 10442141200 10888048640 1178875712 12.580 100 96 182 2 1550568976 351272032 20421232
12:02:53 787 8192000000 10452808768 10887327744 1173764592 12.532 100 94 180 2 1558223056 362643088 21815376
12:03:56 8255 8192000000 10431306592 10862690304 1152744752 12.335 99 69 131 0 1537701840 368732080 16225008
12:05:07 6155 8192000000 10410496048 10898497536 1162511152 12.427 100 60 117 0 1512239472 335276880 14451440
12:06:36 14181 8192000000 10414091104 10835464192 1151340656 12.322 99 55 110 0 1507217072 342441920 13434496
12:07:53 6344 8192000000 10396372736 10822176768 1127390320 12.097 98 61 112 1 1488188208 346970336 13827552
12:09:16 16598 8192000000 10353411536 10792681472 1135454976 12.173 99 37 72 0 1466031712 321221488 9355248
12:10:34 33237 8192000000 10314598880 10782601216 1135738896 12.175 99 29 58 0 1434227584 291208208 7280480
12:12:14 6671 8192000000 10327508208 10779107328 1150161584 12.311 100 45 91 0 1431956560 270781328 11013648
12:13:22 9877 8192000000 10289924800 10731962368 1135587856 12.174 99 40 75 0 1397133968 252794176 8751936
12:14:35 13481 8192000000 10291839456 10757246976 1135470928 12.173 99 34 67 1 1402206400 258417776 8317696
12:15:56 10289 8192000000 10257779904 10726199296 1128732688 12.109 100 45 90 0 1350474176 210695728 11045760
12:17:13 35904 8192000000 10169397008 10653048832 1127441696 12.097 100 43 78 2 1286263808 148452480 10369632
12:18:51 25987 8192000000 10132512992 10591084544 1116072096 11.990 99 24 44 1 1244485792 123245216 5168480
12:20:32 24286 8192000000 10107566000 10519080960 1122945632 12.055 100 48 84 0 1201164288 69830832 8387824
12:21:58 97873 8192000000 10072456688 10556305408 1105678416 11.891 98 27 53 0 1166598160 54939216 5980528
12:24:36 95181 8192000000 10074929552 10543337472 1122924416 12.055 99 16 32 0 1157002384 30503200 3574768
12:28:09 26478 8192000000 10116240208 10538905600 1124304736 12.068 99 32 62 1 1194442144 63132560 7004848
12:29:43 99496 8192000000 10080804528 10545643520 1112482464 11.956 98 11 20 0 1171605968 56942336 2181168
12:32:25 31043 8192000000 10093493952 10530406400 1133904896 12.158 100 36 68 0 1206230176 64426224 7899056
12:33:57 51128 8192000000 10101340320 10533728256 1142994432 12.244 100 39 72 2 1186029728 35139088 7896208
12:35:49 59698 8192000000 10096336752 10548961280 1132760192 12.147 99 41 78 0 1212915072 72085152 8069728
12:37:50 60404 8192000000 10104417712 10531328000 1127157840 12.095 98 28 56 1 1148858592 16236864 5463888
12:39:52 9766 8192000000 10108890608 10545893376 1149313264 12.303 100 46 90 0 1211160896 52284880 9562752
12:41:05 2811 8192000000 10156299808 10586841088 1166296464 12.462 100 66 125 1 1249689376 69265600 14127312
12:42:10 27770 8192000000 10224954096 10687803392 1168846112 12.486 100 59 117 0 1327211504 145664784 12700608
12:43:51 89146 8192000000 10167003616 10630516736 1146101936 12.273 98 24 48 0 1276593616 125512736 4978944
12:46:20 49760 8192000000 10169385152 10636509184 1135686800 12.175 98 21 42 0 1278853600 137782944 5383856
12:48:11 75371 8192000000 10112104096 10556534784 1114672720 11.977 97 16 33 0 1191795888 73296896 3826272
12:50:44 52213 8192000000 10094294672 10542948352 1108665104 11.920 98 15 30 0 1189132240 76946928 3520208
12:52:41 18445 8192000000 10073990624 10532036608 1135043216 12.169 99 54 106 2 1165455584 18380128 12032240
12:54:00 39738 8192000000 10066780080 10504450048 1114002336 11.970 99 39 71 1 1154662720 32996896 7663488
12:55:43 34457 8192000000 10081466448 10535559168 1123472432 12.060 100 29 54 1 1185838112 56624896 5740784
12:57:19 45896 8192000000 10071928464 10529849344 1097961728 11.818 98 29 58 0 1168978160 64969744 6046688
12:59:05 20257 8192000000 10123774112 10568253440 1109232384 11.925 100 43 86 1 1201854848 84106224 8516240
13:01:12 34345 8192000000 10083110464 10510721024 1110148512 11.934 99 38 72 1 1183104208 65356976 7598720
13:02:50 84666 8192000000 10080283344 10536763392 1084111984 11.687 97 30 56 1 1151953312 62350960 5490368
13:05:22 17708 8192000000 10067522992 10544672768 1106585248 11.900 100 23 46 0 1177036400 65680496 4770656
13:07:20 54294 8192000000 10036405680 10519515136 1092026656 11.762 99 12 24 1 1133103264 38275696 2800912
13:09:18 70347 8192000000 10054255552 10524295168 1096791872 11.807 99 22 44 0 1171037616 69678144 4567600
13:12:33 65185 8192000000 10020419056 10474987520 1063463344 11.490 97 14 28 0 1097028384 31010720 2554320
13:14:39 40677 8192000000 10083660528 10525253632 1108744240 11.921 100 18 35 0 1170882624 58194304 3944080
13:16:20 65293 8192000000 10119581904 10591924224 1100737664 11.845 99 10 20 0 1199214320 96303696 2172960
13:18:26 5175 8192000000 10052000208 10479808512 1119289472 12.020 100 60 109 1 1146818208 15425968 12102768
13:19:34 37787 8192000000 10143639152 10585513984 1134913440 12.168 100 47 92 1 1220854608 76545056 9396112
13:21:14 52428 8192000000 10113323344 10611273728 1117006912 11.999 99 36 72 0 1213451376 88929904 7514560
13:23:14 40148 8192000000 10083971248 10535911424 1118695968 12.015 99 27 54 0 1175307680 50756960 5854752
13:25:08 50918 8192000000 10094117808 10577649664 1089913824 11.742 97 26 52 0 1203394944 107829808 5651312
13:27:05 66587 8192000000 10106903008 10609856512 1117234864 12.001 99 26 49 0 1204911040 82597088 5079088
13:29:18 100492 8192000000 10079440256 10543497216 1110124848 11.934 98 42 82 1 1152574000 33469008 8980144
13:32:10 34772 8192000000 10054857888 10535952384 1095558192 11.795 98 16 32 0 1144101200 45123040 3419968
13:34:03 70344 8192000000 10055736992 10518118400 1130799312 12.129 100 6 12 0 1155607504 23478256 1329936
13:36:21 39506 8192000000 10070552912 10521694208 1123114512 12.056 99 18 36 0 1164836736 37705696 4016528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-12-2022 13:40:03 to 13-12-2022 13:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 66 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 13-12-2022 13:40:20 to 13-12-2022 13:40:22.

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          218174
MON$OLDEST_ACTIVE               222056
MON$OLDEST_SNAPSHOT             222056
MON$NEXT_TRANSACTION            222056
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                       2113024
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2884 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2884 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2884 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2884 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 ---
:236: 
:237: mscs-test-161.budzdorov.ru    Tue Dec 13 13:37:38 2022
:238:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:239: 
--- end of diff output ---

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

13.12.22 13:40:24. 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-12-13 13:40:26 No severe PSQL-related problems occured
13.12.22 13:40:28. End of report.