Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.09.2021 13:37
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15905524 kB
MemAvailable 30779368 kB
Buffers 0 kB
Cached 15759604 kB
SwapCached 696 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.1
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 2021-09-20 13:37:06
DTS_END                         2021-09-20 13:37:06.9340
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/36844
TRN_ID                          230616
ATT_ID                          630
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: 8996.97 22948 1079636
customer order: creation 161.17 445 19340
customer order: refuse 13.33 330 1599
order to supplier: creation 43.07 1344 5168
order to supplier: removal 3.18 120820 382
invoice (draft): creation 43.28 3621 5194
invoice (draft): removal 54.43 874 6532
invoice accept: apply 39.07 18625 4688
invoice accept: cancel 49.25 882 5910
customer reserve: creation 3533.38 186 424005
customer reserve: removal 4769.51 64 572341
realization accept: apply 79.13 215 9496
realization accept: cancel 86.25 113 10350
payment from customer: creation 56.91 129 6829
payment from customer: removal 5.07 57 608
payment to supplier: creation 45.68 147 5481
payment to supplier: removal 3.44 80 413
service: total inventory turnovers 3.78 3440 453
service: total monetary turnovers 6.02 559 722
service: aggregate perf. data 1.00 12761 120
service: refresh index statistics 0.04 294270 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-09-2021 13:38:11 to 20-09-2021 13:38:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6956
TEST_TIME 2 6918
TEST_TIME 3 6680
TEST_TIME 4 6233
TEST_TIME 5 6101
TEST_TIME 6 5819
TEST_TIME 7 5594
TEST_TIME 8 5454
TEST_TIME 9 5627
TEST_TIME 10 6130
TEST_TIME 11 6249
TEST_TIME 12 6417
TEST_TIME 13 6661
TEST_TIME 14 7060
TEST_TIME 15 7444
TEST_TIME 16 8059
TEST_TIME 17 8494
TEST_TIME 18 8924
TEST_TIME 19 8837
TEST_TIME 20 9063
TEST_TIME 21 9445
TEST_TIME 22 9281
TEST_TIME 23 9451
TEST_TIME 24 10238
TEST_TIME 25 10724
TEST_TIME 26 10350
TEST_TIME 27 10341
TEST_TIME 28 10503
TEST_TIME 29 10342
TEST_TIME 30 10396
TEST_TIME 31 10177
TEST_TIME 32 10007
TEST_TIME 33 9896
TEST_TIME 34 9703
TEST_TIME 35 9812
TEST_TIME 36 9716
TEST_TIME 37 9527
TEST_TIME 38 9400
TEST_TIME 39 9278
TEST_TIME 40 9310
TEST_TIME 41 9260
TEST_TIME 42 9162
TEST_TIME 43 9163
TEST_TIME 44 9143
TEST_TIME 45 9285
TEST_TIME 46 9294
TEST_TIME 47 9435
TEST_TIME 48 9387
TEST_TIME 49 9278
TEST_TIME 50 9320
TEST_TIME 51 9395
TEST_TIME 52 9345
TEST_TIME 53 9374
TEST_TIME 54 9397
TEST_TIME 55 9493
TEST_TIME 56 9429
TEST_TIME 57 9319
TEST_TIME 58 9290
TEST_TIME 59 9218
TEST_TIME 60 9170
TEST_TIME 61 9153
TEST_TIME 62 9218
TEST_TIME 63 9097
TEST_TIME 64 9003
TEST_TIME 65 9093
TEST_TIME 66 9135
TEST_TIME 67 9126
TEST_TIME 68 9099
TEST_TIME 69 9133
TEST_TIME 70 9170
TEST_TIME 71 9298
TEST_TIME 72 9308
TEST_TIME 73 9429
TEST_TIME 74 9442
TEST_TIME 75 9434
TEST_TIME 76 9467
TEST_TIME 77 9400
TEST_TIME 78 9341
TEST_TIME 79 9251
TEST_TIME 80 9340
TEST_TIME 81 9273
TEST_TIME 82 9218
TEST_TIME 83 9209
TEST_TIME 84 9208
TEST_TIME 85 9165
TEST_TIME 86 9073
TEST_TIME 87 9016
TEST_TIME 88 9051
TEST_TIME 89 9041
TEST_TIME 90 9004
TEST_TIME 91 9052
TEST_TIME 92 9062
TEST_TIME 93 9079
TEST_TIME 94 9145
TEST_TIME 95 9173
TEST_TIME 96 9181
TEST_TIME 97 9220
TEST_TIME 98 9208
TEST_TIME 99 9222
TEST_TIME 100 9236
TEST_TIME 101 9220
TEST_TIME 102 9302
TEST_TIME 103 9286
TEST_TIME 104 9274
TEST_TIME 105 9227
TEST_TIME 106 9156
TEST_TIME 107 9148
TEST_TIME 108 9107
TEST_TIME 109 9068
TEST_TIME 110 9062
TEST_TIME 111 9050
TEST_TIME 112 9031
TEST_TIME 113 9022
TEST_TIME 114 9013
TEST_TIME 115 9021
TEST_TIME 116 9024
TEST_TIME 117 9052
TEST_TIME 118 9040
TEST_TIME 119 9076
TEST_TIME 120 9003

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-09-2021 13:38:15 to 20-09-2021 13:38:18.

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 1472739 1472739 0 0.00 0 30981 7 0 0
doc_list_biud 1472739 1472739 0 0.00 0 54525 32 0 0
sp_add_invoice_to_stock 4815 4688 127 2.63 46 228607 18626 0 127
sp_cancel_adding_invoice 5910 5910 0 0.00 6 64249 883 0 0
sp_cancel_client_order 1599 1599 0 0.00 5 16445 331 0 0
sp_cancel_customer_reserve 572341 572341 0 0.00 1 31116 64 0 0
sp_cancel_pay_from_customer 608 608 0 0.00 3 1791 57 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 5353 81 0 0
sp_cancel_supplier_invoice 6544 6532 12 0.18 2 54559 875 0 12
sp_cancel_supplier_order 382 382 0 0.00 11 1012081 120820 0 0
sp_cancel_write_off 10350 10350 0 0.00 1 20073 114 0 0
sp_client_order 19340 19340 0 0.00 4 31211 445 0 0
sp_customer_reserve 424005 424005 0 0.00 0 113052 187 0 0
sp_fill_shopping_cart 29699 29699 0 0.00 1 40705 49 0 0
sp_fill_shopping_cart_clo_res 423987 423987 0 0.00 0 17444 16 0 0
sp_get_clo_for_invoice 4670 4670 0 0.00 0 25176 377 0 0
sp_kill_cost_storno 17281 17281 0 0.00 0 30318 61 0 0
sp_kill_qstorno_ret_qs2qd 1161946 1161946 0 0.00 0 54517 35 0 0
sp_kill_qty_storno 611280 611280 0 0.00 0 54523 76 0 0
sp_lock_selected_doc 19526 19526 0 0.00 0 8605 6 0 0
sp_make_cost_storno 26476 26476 0 0.00 0 20445 62 0 0
sp_multiply_rows_for_pdistr 26476 26476 0 0.00 0 10013 35 0 0
sp_multiply_rows_for_qdistr 19340 19340 0 0.00 0 30136 232 0 0
sp_pay_from_customer 6829 6829 0 0.00 4 20649 129 0 0
sp_pay_to_supplier 5481 5481 0 0.00 5 25234 147 0 0
sp_payment_common 12310 12310 0 0.00 1 10161 23 0 0
sp_qd_handle_on_cancel_clo 1599 1599 0 0.00 0 16422 294 0 0
sp_qd_handle_on_invoice_upd_sts 10580 10580 0 0.00 0 30426 382 0 0
sp_qd_handle_on_reserve_upd_sts 9496 9496 0 0.00 0 23941 76 0 0
sp_reserve_write_off 9496 9496 0 0.00 7 23957 216 0 0
sp_supplier_invoice 5194 5194 0 0.00 7 79876 3621 0 0
sp_supplier_order 5168 5168 0 0.00 5 74726 1344 0 0
srv_aggregate_perf_data 120 120 0 0.00 2937 111099 12762 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2202 136876 47301 0 0
srv_find_qd_qs_mism 1035348 1035348 0 0.00 0 30980 5 0 0
srv_make_invnt_saldo 453 453 0 0.00 55 134790 3440 0 0
srv_make_money_saldo 722 722 0 0.00 1 14329 559 0 0
srv_recalc_idx_stat 5 5 0 0.00 135244 436369 294270 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4688 4688 0 0.00 0 13490 175 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 0 6859 127 0 0
t$perf-norm:sp_cancel_client_order 1599 1599 0 0.00 0 4775 15 0 0
t$perf-norm:sp_cancel_customer_reserve 620 620 0 0.00 0 10943 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 608 608 0 0.00 0 993 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 5289 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 2964 19 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 28042 1774 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 2311 8 0 0
t$perf-norm:sp_client_order 19340 19340 0 0.00 0 9947 18 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 3466 11 0 0
t$perf-norm:sp_pay_from_customer 6829 6829 0 0.00 0 10000 10 0 0
t$perf-norm:sp_pay_to_supplier 5481 5481 0 0.00 0 6171 12 0 0
t$perf-norm:sp_reserve_write_off 9496 9496 0 0.00 0 6935 10 0 0
t$perf-norm:sp_supplier_invoice 5194 5194 0 0.00 0 8739 22 0 0
t$perf-norm:sp_supplier_order 5168 5168 0 0.00 0 5761 19 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 419 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 81 2 0 0
t$perf-norm:srv_make_invnt_saldo 453 453 0 0.00 0 3151 13 0 0
t$perf-norm:srv_make_money_saldo 722 722 0 0.00 0 1107 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9590 9336 254 2.64 0 20018 23 0 254
v_all_customers 35600 35600 0 0.00 0 18 1 0 0
v_all_suppliers 28706 28706 0 0.00 0 19 1 0 0
v_all_wares 38680 38680 0 0.00 0 99 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 613 7 0 0
v_cancel_client_order 3198 3198 0 0.00 0 8266 11 0 0
v_cancel_customer_prepayment 1216 1216 0 0.00 0 396 4 0 0
v_cancel_customer_reserve 1240 1240 0 0.00 0 1333 5 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 730 6 0 0
v_cancel_supplier_invoice 836 812 24 2.87 0 11036 29 0 24
v_cancel_supplier_order 764 764 0 0.00 0 2587 6 0 0
v_cancel_write_off 1892 1892 0 0.00 0 20041 22 0 0
v_max_id_clo_ord 5168 5168 0 0.00 0 697 1 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 7362 23 0 0
v_max_id_ord_sup 5191 5191 0 0.00 0 7236 8 0 0
v_max_non_paid_invoice 1487 1487 0 0.00 0 585 3 0 0
v_max_non_paid_realizn 3579 3579 0 0.00 0 2275 4 0 0
v_min_id_clo_ord 5168 5168 0 0.00 0 19690 8 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 2792 9 0 0
v_min_id_ord_sup 5191 5191 0 0.00 0 3960 7 0 0
v_min_non_paid_invoice 5481 5481 0 0.00 0 4932 14 0 0
v_min_non_paid_realizn 6829 6829 0 0.00 0 7809 18 0 0
v_our_firm 9580 9580 0 0.00 0 16 1 0 0
v_reserve_write_off 18992 18992 0 0.00 0 20597 10 0 0
x_lock_depdocs_on_canc_invoice 12036 12036 0 0.00 0 13089 99 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 3402 317 0 0
x_make_qstorno_1000_1200 5168 5168 0 0.00 2 74640 1252 0 0
x_make_qstorno_1200_2000 5194 5194 0 0.00 4 78055 3439 0 0
x_make_qstorno_2100_3300 424005 424005 0 0.00 0 113052 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-09-2021 13:38:19 to 20-09-2021 13:38:23.

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:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:11 13556 8192000000 9700321920 9993142272 788210304 8.777 100 89 178 0 816789056 6767728 21811024
11:09:28 17744 8192000000 9747947376 10051190784 824626064 9.145 100 86 172 0 856496560 11258272 20612224
11:11:06 13343 8192000000 9792414272 10111799296 863204080 9.532 100 84 168 0 906333632 23865712 19263840
11:12:29 12747 8192000000 9863484688 10195283968 915841472 10.055 100 74 148 0 968343104 36355296 16146336
11:13:45 10688 8192000000 9907309328 10248921088 956764992 10.457 100 82 164 2 1030005040 56081744 17158304
11:14:58 6921 8192000000 9967463536 10316255232 994522848 10.825 100 86 172 0 1073257456 60974672 17759936
11:16:07 3017 8192000000 10008746128 10366574592 1019073280 11.063 100 88 172 1 1121786000 85343616 17369104
11:17:12 2293 8192000000 10006045136 10378608640 1043031584 11.294 100 79 157 1 1125386672 65988448 16366640
11:18:15 1383 8192000000 10047470432 10417373184 1068843904 11.541 100 90 180 2 1154045008 67052608 18148496
11:19:17 3431 8192000000 10008490464 10399465472 1072405856 11.575 100 52 103 0 1123989536 40723664 10860016
11:20:21 6636 8192000000 9997379584 10391171072 1080080272 11.648 100 34 66 0 1120256960 33025344 7151344
11:21:28 6856 8192000000 9993794544 10397773824 1091065024 11.753 100 45 88 1 1117708544 15918384 10725136
11:22:43 46740 8192000000 10001291376 10412527616 1095995856 11.800 100 25 48 1 1121124592 19689328 5439408
11:24:30 5276 8192000000 10026140320 10429018112 1100423248 11.842 100 48 94 0 1144692624 33967056 10302320
11:25:36 4774 8192000000 10020373952 10419290112 1100392672 11.841 100 38 77 1 1134585552 25726976 8465904
11:26:44 4591 8192000000 10022612912 10422661120 1101313344 11.850 100 51 102 0 1133568240 20266848 11988048
11:27:50 30676 8192000000 9981877920 10385711104 1005950016 10.936 92 29 58 0 1045073088 32934816 6188256
11:29:21 4443 8192000000 9934650640 10323697664 1020832912 11.080 97 37 74 2 1053128224 23543568 8751744
11:30:27 7423 8192000000 9915179856 10296557568 977086160 10.656 97 37 70 4 1008133120 22844880 8202080
11:31:35 8304 8192000000 9931628448 10313433088 1002241552 10.900 98 42 85 0 1027649088 15755280 9652256
11:32:44 17594 8192000000 9958493504 10362187776 1030038448 11.169 100 24 48 3 1065201584 29719632 5443504
11:34:04 3965 8192000000 9972909056 10357043200 1049316512 11.354 100 50 98 1 1085998384 26909600 9772272
11:35:09 5084 8192000000 9991300736 10390306816 1062282208 11.478 100 43 89 2 1095346960 23860352 9204400
11:36:18 61426 8192000000 10008041824 10402934784 1072035904 11.572 100 43 86 0 1103348256 22587632 8724720
11:38:33 2660 8192000000 10054889632 10455818240 1103111600 11.867 100 56 109 2 1169247168 54855056 11280512
11:39:44 9700 8192000000 10083531952 10483314688 1117689504 12.005 100 50 100 0 1187369616 58941456 10738656
11:40:56 16685 8192000000 10130720928 10537242624 1132868928 12.148 100 60 115 2 1222417488 76730848 12817712
11:42:16 19761 8192000000 10161769632 10575249408 1142526928 12.239 100 46 90 0 1264179728 111943376 9709424
11:43:55 13889 8192000000 10194471456 10607390720 1149982416 12.309 100 46 92 0 1304373408 144086736 10304256
11:45:15 9239 8192000000 10237877360 10654113792 1165797904 12.458 100 46 92 0 1355697344 180026800 9872640
11:46:30 12769 8192000000 10280546864 10704818176 1170357168 12.500 100 54 108 1 1399313248 215424304 13531776
11:47:55 5068 8192000000 10287997024 10707861504 1185000928 12.637 100 44 87 0 1404288640 208504192 10783520
11:49:05 3393 8192000000 10340990096 10743656448 1173598384 12.530 100 80 159 0 1439889904 248423008 17868512
11:50:12 2238 8192000000 10374061168 10781618176 1153656928 12.344 99 86 171 2 1472604832 299633184 19314720
11:51:17 2197 8192000000 10388216224 10795356160 1152569344 12.334 100 76 151 0 1491890784 321656672 17664768
11:52:26 4226 8192000000 10381909264 10789715968 1159793072 12.401 100 73 144 0 1475481984 299119584 16569328
11:53:36 8591 8192000000 10317189840 10730016768 1158692592 12.391 100 64 126 1 1422909072 249251344 14965136
11:54:49 12878 8192000000 10327712496 10762854400 1152424368 12.332 100 40 80 1 1431836480 268507488 10904624
11:56:03 12191 8192000000 10270813024 10699104256 1148173728 12.292 100 24 48 0 1370711776 216060144 6477904
11:57:20 15643 8192000000 10298302192 10755149824 1141777984 12.232 100 29 58 0 1418654096 269502432 7373680
11:58:44 18226 8192000000 10290929472 10771689472 1127663472 12.099 99 32 62 1 1421190688 285850896 7676320
12:00:26 43914 8192000000 10260473488 10732920832 1095214176 11.792 98 23 47 1 1371059744 269581488 6264080
12:02:12 11475 8192000000 10173570576 10600996864 1110601792 11.938 100 29 56 3 1263514176 146099392 6812992
12:03:25 76569 8192000000 10154171120 10611720192 1099577296 11.834 99 54 105 3 1268900112 157772928 11549888
12:06:02 18918 8192000000 10063870336 10518192128 1087271152 11.717 99 17 34 0 1176762624 85499856 3991616
12:08:31 53507 8192000000 10087587616 10525093888 1129895072 12.120 100 40 78 1 1170488048 31887600 8705376
12:10:39 91291 8192000000 10079364752 10583654400 1136584304 12.183 100 17 34 0 1222419728 81852592 3982832
12:13:13 55078 8192000000 10078929248 10530418688 1122574032 12.051 98 15 30 0 1162686512 37346048 2766432
12:15:28 68664 8192000000 10187000912 10648006656 1153644256 12.344 100 31 61 0 1261521312 101499248 6377808
12:17:41 66529 8192000000 10185421488 10690682880 1140327696 12.219 99 13 26 2 1276555904 133111008 3117200
12:19:48 47795 8192000000 10177336528 10666852352 1151264096 12.321 100 14 28 1 1275598608 121285856 3048656
12:21:38 62147 8192000000 10087510192 10560274432 1116299904 11.992 97 33 70 0 1228138720 104668288 7170528
12:23:47 104611 8192000000 10090597056 10556256256 1144424608 12.257 99 14 27 0 1200838144 53730128 2683408
12:27:05 34339 8192000000 10117190368 10592395264 1144955920 12.262 99 20 40 0 1227782880 78974592 3852368
12:28:43 65502 8192000000 10088513056 10544136192 1105314464 11.888 96 22 44 0 1147431040 37272992 4843584
12:30:50 109708 8192000000 10103225376 10620174336 1145670320 12.269 99 5 10 0 1215680544 69120144 890080
12:33:43 102719 8192000000 10087234640 10564083712 1146135408 12.273 99 31 62 0 1189206960 35987360 7084192
12:36:34 37215 8192000000 10134519360 10574147584 1142835296 12.242 98 31 62 1 1207771712 58494048 6442368
12:38:17 34171 8192000000 10127572976 10594930688 1157551616 12.380 100 12 24 1 1232547712 72473376 2522720
12:40:08 136876 8192000000 10156063072 10625355776 1101085216 11.848 95 11 22 0 1163793344 60617488 2090640
12:43:26 49597 8192000000 10101001072 10554613760 1102906592 11.865 96 17 35 0 1166099536 59585680 3607264
12:45:22 61521 8192000000 10126634496 10592468992 1085138224 11.696 95 17 34 1 1187536912 98785040 3613648
12:47:27 53830 8192000000 10178201568 10615926784 1113565040 11.966 98 29 58 0 1253428320 133701504 6161776
12:49:31 73135 8192000000 10116962896 10594766848 1104303168 11.878 97 12 24 0 1181660464 74863936 2493360
12:51:44 42652 8192000000 10083901488 10509389824 1072592032 11.577 95 31 62 0 1115843760 37005088 6246640
12:53:27 43599 8192000000 10073911824 10492063744 1080884384 11.656 96 39 78 0 1123025664 34227200 7914080
12:55:13 112654 8192000000 10123287312 10554318848 1109265056 11.925 99 34 68 0 1224546096 107769152 7511888
12:58:06 56942 8192000000 10068403424 10518896640 1107732432 11.911 98 17 34 0 1147874880 36794640 3347808
13:00:22 66312 8192000000 10057137776 10504286208 1099182704 11.830 97 15 29 1 1162154176 59570800 3400672
13:02:38 71877 8192000000 10079084304 10516021248 1124836304 12.073 99 22 44 0 1161276640 31761968 4678368
13:04:53 57405 8192000000 10090817520 10595508224 1123710544 12.062 99 24 48 1 1194152432 65326128 5115760
13:06:51 67404 8192000000 10112214864 10589700096 1125685312 12.081 99 13 26 1 1203056736 74902784 2468640
13:08:58 52770 8192000000 10117825120 10562719744 1118936560 12.017 98 19 39 0 1196153792 73075856 4141376
13:10:51 58382 8192000000 10112947008 10581098496 1129590480 12.118 99 16 32 0 1208242240 75359776 3291984
13:13:10 57788 8192000000 10115421168 10566991872 1137578800 12.193 99 31 62 1 1211623232 67830112 6214320
13:15:08 50786 8192000000 10103280192 10561773568 1134708480 12.166 99 32 62 0 1187491936 46730592 6052864
13:16:59 22781 8192000000 10094145312 10540720128 1149677152 12.306 100 47 93 0 1196189824 37414848 9097824
13:18:22 27923 8192000000 10156092480 10601168896 1147939680 12.290 99 31 62 0 1226249264 72002816 6306768
13:20:01 118224 8192000000 10120695024 10615476224 1134311632 12.162 98 18 36 0 1201745760 63924848 3509280
13:24:13 64968 8192000000 10079431632 10557054976 1139751600 12.213 99 20 40 2 1173941344 30156048 4033696
13:26:24 51910 8192000000 10107300528 10570592256 1134979984 12.168 98 11 22 0 1200502720 62842752 2679984
13:28:17 62109 8192000000 10084398128 10537062400 1085056752 11.696 95 20 39 0 1150273728 60964400 4252576
13:30:35 61893 8192000000 10062015808 10516090880 1106883856 11.903 98 14 26 0 1174959856 65378496 2697504
13:32:39 44248 8192000000 10114453552 10558431232 1135100448 12.169 99 26 50 1 1202831008 62410464 5320096
13:34:24 62519 8192000000 10137489968 10625552384 1135154960 12.170 100 20 40 1 1239326512 99379328 4792224
13:36:34 34853 8192000000 9790102688 10192891904 850785232 9.408 74 16 30 1 928364016 73579504 3999280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-09-2021 13:38:25 to 20-09-2021 13:38:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-09-2021 13:38:41 to 20-09-2021 13:38:43.

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          226496
MON$OLDEST_ACTIVE               230899
MON$OLDEST_SNAPSHOT             230899
MON$NEXT_TRANSACTION            230899
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                       2051872
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.1.2606 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2606 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2606 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2606 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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Mon Sep 20 13:37:17 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Mon Sep 20 13:37:17 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
--- end of diff output ---

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

20.09.21 13:38:44. 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
2021-09-20 13:38:46 No severe PSQL-related problems occured
20.09.21 13:38:48. End of report.