Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.12.2022 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 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 14835404 kB
MemAvailable 30054936 kB
Buffers 0 kB
Cached 16826208 kB
SwapCached 3284 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-21 13:37:23
DTS_END                         2022-12-21 13:37:23.3960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55644
TRN_ID                          218645
ATT_ID                          580
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: 9025.23 25660 1083027
customer order: creation 166.29 445 19955
customer order: refuse 14.12 391 1694
order to supplier: creation 44.53 1265 5344
order to supplier: removal 3.26 156586 391
invoice (draft): creation 45.80 3106 5496
invoice (draft): removal 50.13 939 6016
invoice accept: apply 40.67 26409 4881
invoice accept: cancel 45.53 1176 5463
customer reserve: creation 3859.86 249 463183
customer reserve: removal 4460.94 91 535313
realization accept: apply 81.66 277 9799
realization accept: cancel 84.20 150 10104
payment from customer: creation 58.95 177 7074
payment from customer: removal 5.29 123 635
payment to supplier: creation 48.25 177 5790
payment to supplier: removal 3.32 127 398
service: total inventory turnovers 4.30 3176 516
service: total monetary turnovers 7.23 331 867
service: aggregate perf. data 0.87 15279 104
service: refresh index statistics 0.03 302733 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-12-2022 13:38:13 to 21-12-2022 13:38:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7218
TEST_TIME 2 6833
TEST_TIME 3 7491
TEST_TIME 4 7759
TEST_TIME 5 7287
TEST_TIME 6 6783
TEST_TIME 7 6608
TEST_TIME 8 6258
TEST_TIME 9 6142
TEST_TIME 10 6222
TEST_TIME 11 6322
TEST_TIME 12 6212
TEST_TIME 13 6162
TEST_TIME 14 6365
TEST_TIME 15 6453
TEST_TIME 16 6559
TEST_TIME 17 6689
TEST_TIME 18 6808
TEST_TIME 19 6946
TEST_TIME 20 7453
TEST_TIME 21 7733
TEST_TIME 22 8041
TEST_TIME 23 8106
TEST_TIME 24 8687
TEST_TIME 25 9099
TEST_TIME 26 9549
TEST_TIME 27 9959
TEST_TIME 28 10375
TEST_TIME 29 10423
TEST_TIME 30 10344
TEST_TIME 31 10613
TEST_TIME 32 10398
TEST_TIME 33 10485
TEST_TIME 34 10719
TEST_TIME 35 10562
TEST_TIME 36 10503
TEST_TIME 37 10398
TEST_TIME 38 10226
TEST_TIME 39 10296
TEST_TIME 40 10193
TEST_TIME 41 10094
TEST_TIME 42 10089
TEST_TIME 43 10119
TEST_TIME 44 10080
TEST_TIME 45 10047
TEST_TIME 46 9989
TEST_TIME 47 9982
TEST_TIME 48 9980
TEST_TIME 49 9788
TEST_TIME 50 9673
TEST_TIME 51 9650
TEST_TIME 52 9638
TEST_TIME 53 9531
TEST_TIME 54 9565
TEST_TIME 55 9495
TEST_TIME 56 9542
TEST_TIME 57 9574
TEST_TIME 58 9535
TEST_TIME 59 9472
TEST_TIME 60 9462
TEST_TIME 61 9432
TEST_TIME 62 9351
TEST_TIME 63 9327
TEST_TIME 64 9312
TEST_TIME 65 9313
TEST_TIME 66 9271
TEST_TIME 67 9315
TEST_TIME 68 9362
TEST_TIME 69 9365
TEST_TIME 70 9373
TEST_TIME 71 9368
TEST_TIME 72 9418
TEST_TIME 73 9440
TEST_TIME 74 9405
TEST_TIME 75 9515
TEST_TIME 76 9618
TEST_TIME 77 9657
TEST_TIME 78 9592
TEST_TIME 79 9540
TEST_TIME 80 9522
TEST_TIME 81 9519
TEST_TIME 82 9503
TEST_TIME 83 9429
TEST_TIME 84 9396
TEST_TIME 85 9364
TEST_TIME 86 9316
TEST_TIME 87 9285
TEST_TIME 88 9234
TEST_TIME 89 9152
TEST_TIME 90 9161
TEST_TIME 91 9162
TEST_TIME 92 9142
TEST_TIME 93 9125
TEST_TIME 94 9183
TEST_TIME 95 9223
TEST_TIME 96 9196
TEST_TIME 97 9253
TEST_TIME 98 9264
TEST_TIME 99 9270
TEST_TIME 100 9320
TEST_TIME 101 9316
TEST_TIME 102 9344
TEST_TIME 103 9365
TEST_TIME 104 9314
TEST_TIME 105 9250
TEST_TIME 106 9219
TEST_TIME 107 9214
TEST_TIME 108 9187
TEST_TIME 109 9150
TEST_TIME 110 9142
TEST_TIME 111 9124
TEST_TIME 112 9097
TEST_TIME 113 9098
TEST_TIME 114 9053
TEST_TIME 115 9060
TEST_TIME 116 9039
TEST_TIME 117 9076
TEST_TIME 118 9092
TEST_TIME 119 9108
TEST_TIME 120 9049

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-12-2022 13:38:17 to 21-12-2022 13:38:20.

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 1508898 1508898 0 0.00 0 15214 13 0 0
doc_list_biud 1508885 1508885 0 0.00 0 40577 38 0 0
sp_add_invoice_to_stock 4951 4881 70 1.41 30 206502 26410 0 70
sp_cancel_adding_invoice 5463 5463 0 0.00 3 120408 1176 0 0
sp_cancel_client_order 1694 1694 0 0.00 6 8702 391 0 0
sp_cancel_customer_reserve 535313 535313 0 0.00 1 40580 91 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 3 4815 124 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 4 6777 127 0 0
sp_cancel_supplier_invoice 6021 6016 5 0.08 3 12028 940 0 5
sp_cancel_supplier_order 391 391 0 0.00 10 1246442 156586 0 0
sp_cancel_write_off 10104 10104 0 0.00 2 11706 150 0 0
sp_client_order 19955 19955 0 0.00 5 21090 446 0 0
sp_customer_reserve 463183 463183 0 0.00 0 58099 249 0 0
sp_fill_shopping_cart 30783 30783 0 0.00 1 10092 76 0 0
sp_fill_shopping_cart_clo_res 463150 463150 0 0.00 0 18550 18 0 0
sp_get_clo_for_invoice 4838 4838 0 0.00 0 29371 310 0 0
sp_kill_cost_storno 16600 16600 0 0.00 0 6727 67 0 0
sp_kill_qstorno_ret_qs2qd 1087137 1087137 0 0.00 0 40571 41 0 0
sp_kill_qty_storno 573616 573616 0 0.00 0 40577 92 0 0
sp_lock_selected_doc 20225 20225 0 0.00 0 5807 6 0 0
sp_make_cost_storno 27500 27500 0 0.00 0 14571 80 0 0
sp_multiply_rows_for_pdistr 27498 27498 0 0.00 0 9168 40 0 0
sp_multiply_rows_for_qdistr 19955 19955 0 0.00 0 16904 164 0 0
sp_pay_from_customer 7074 7074 0 0.00 5 13923 178 0 0
sp_pay_to_supplier 5790 5790 0 0.00 6 12277 177 0 0
sp_payment_common 12862 12862 0 0.00 2 8816 47 0 0
sp_qd_handle_on_cancel_clo 1694 1694 0 0.00 0 8675 322 0 0
sp_qd_handle_on_invoice_upd_sts 10300 10300 0 0.00 0 19452 467 0 0
sp_qd_handle_on_reserve_upd_sts 9798 9798 0 0.00 0 12558 57 0 0
sp_reserve_write_off 9799 9799 0 0.00 8 15222 278 0 0
sp_supplier_invoice 5496 5496 0 0.00 9 72707 3107 0 0
sp_supplier_order 5344 5344 0 0.00 8 20407 1266 0 0
srv_aggregate_perf_data 104 104 0 0.00 2161 92069 15279 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 832 144327 35850 0 0
srv_find_qd_qs_mism 1034319 1034319 0 0.00 0 11692 10 0 0
srv_make_invnt_saldo 516 516 0 0.00 57 58836 3177 0 0
srv_make_money_saldo 867 867 0 0.00 1 10783 332 0 0
srv_recalc_idx_stat 4 4 0 0.00 60689 541654 302734 0 0
t$perf-abend:sp_add_invoice_to_stock 70 70 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4887 4887 0 0.00 1 8696 746 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 1 6351 456 0 0
t$perf-norm:sp_cancel_client_order 1694 1694 0 0.00 0 1650 21 0 0
t$perf-norm:sp_cancel_customer_reserve 624 624 0 0.00 0 1618 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 2646 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 466 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 2293 34 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 80597 5861 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 8269 27 0 0
t$perf-norm:sp_client_order 19956 19956 0 0.00 0 12193 33 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 1008 15 0 0
t$perf-norm:sp_pay_from_customer 7074 7074 0 0.00 0 11042 17 0 0
t$perf-norm:sp_pay_to_supplier 5791 5791 0 0.00 0 7102 18 0 0
t$perf-norm:sp_reserve_write_off 9799 9799 0 0.00 0 5502 23 0 0
t$perf-norm:sp_supplier_invoice 5496 5496 0 0.00 0 11048 28 0 0
t$perf-norm:sp_supplier_order 5344 5344 0 0.00 0 3527 26 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 391 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 1240 28 0 0
t$perf-norm:srv_make_invnt_saldo 516 516 0 0.00 0 1522 12 0 0
t$perf-norm:srv_make_money_saldo 867 867 0 0.00 0 1916 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 149 44 0 0
v_add_invoice_to_stock 9807 9667 140 1.42 0 9210 26 0 140
v_all_customers 36980 36980 0 0.00 0 71 3 0 0
v_all_suppliers 30854 30854 0 0.00 0 118 4 0 0
v_all_wares 39902 39902 0 0.00 0 81 3 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 2291 18 0 0
v_cancel_client_order 3388 3388 0 0.00 0 5085 12 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 3505 16 0 0
v_cancel_customer_reserve 1248 1248 0 0.00 0 2992 16 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 6692 21 0 0
v_cancel_supplier_invoice 792 782 10 1.26 0 1451 24 0 10
v_cancel_supplier_order 782 782 0 0.00 0 4278 20 0 0
v_cancel_write_off 2048 2048 0 0.00 0 5546 30 0 0
v_max_id_clo_ord 5340 5340 0 0.00 0 882 5 0 0
v_max_id_clo_res 985 985 0 0.00 0 8503 21 0 0
v_max_id_ord_sup 5487 5487 0 0.00 0 2285 5 0 0
v_max_non_paid_invoice 1188 1188 0 0.00 0 629 7 0 0
v_max_non_paid_realizn 3540 3540 0 0.00 0 5040 9 0 0
v_min_id_clo_ord 5340 5340 0 0.00 0 3492 8 0 0
v_min_id_clo_res 985 985 0 0.00 0 1465 8 0 0
v_min_id_ord_sup 5487 5487 0 0.00 0 3819 7 0 0
v_min_non_paid_invoice 5788 5788 0 0.00 0 7458 19 0 0
v_min_non_paid_realizn 7074 7074 0 0.00 0 8812 25 0 0
v_our_firm 9990 9990 0 0.00 0 118 4 0 0
v_reserve_write_off 19590 19590 0 0.00 0 6849 15 0 0
x_lock_depdocs_on_canc_invoice 11088 11088 0 0.00 0 18101 119 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 4198 328 0 0
x_make_qstorno_1000_1200 5344 5344 0 0.00 3 20265 1122 0 0
x_make_qstorno_1200_2000 5496 5496 0 0.00 3 72661 2891 0 0
x_make_qstorno_2100_3300 463183 463183 0 0.00 0 58042 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-12-2022 13:38:21 to 21-12-2022 13:38:25.

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:27 4 8192000000 8803298192 8816418816 8027264 0.097 4 2 3 0 8171664 83040 61360
11:08:28 34787 8192000000 9719585152 10015993856 791614592 8.811 100 87 174 0 820420960 7592480 21213888
11:10:14 20484 8192000000 9757618304 10064904192 835466080 9.254 100 85 169 0 868588288 13122080 20000128
11:11:39 10611 8192000000 9797922576 10116685824 866444832 9.565 100 82 164 0 903801024 18705456 18650736
11:12:52 4931 8192000000 9851981600 10185420800 906102768 9.959 100 76 150 2 965567104 43262944 16201392
11:13:59 5334 8192000000 9930129952 10272276480 948226816 10.374 100 80 159 1 1036177072 71314704 16635552
11:15:07 1830 8192000000 9965522544 10319089664 989034544 10.772 100 90 180 0 1087380464 79577952 18767968
11:16:13 1253 8192000000 10009035136 10369798144 1010399600 10.979 100 94 181 2 1126035968 98029328 17607040
11:17:16 1147 8192000000 10009517616 10375598080 1035104960 11.218 100 92 176 0 1129986000 76981008 17900032
11:18:18 961 8192000000 10030846864 10404229120 1053355600 11.393 100 93 164 0 1152136992 82067568 16713824
11:19:20 1057 8192000000 10026401440 10408869888 1060263648 11.459 100 94 179 0 1143810096 64688720 18857728
11:20:22 1012 8192000000 10057107968 10435948544 1067168448 11.525 100 96 184 0 1177419280 91327184 18923648
11:21:24 9093 8192000000 10046768368 10429980672 1077280496 11.622 100 74 140 1 1160590224 68419168 14890560
11:22:34 1107 8192000000 10027241792 10414338048 1082045168 11.667 100 83 151 1 1143026832 44769136 16212528
11:23:36 4875 8192000000 10029687968 10424373248 1084529040 11.691 100 58 112 2 1151549488 55658640 11361808
11:24:42 1214 8192000000 10036952512 10426351616 1090075840 11.743 100 74 142 1 1160038480 55296112 14666528
11:25:43 1285 8192000000 10072195344 10459910144 1091527280 11.757 100 95 182 2 1188729520 79042832 18159408
11:26:46 2029 8192000000 10046441984 10441646080 1098229120 11.821 100 86 166 2 1174941680 58574816 18137744
11:27:53 1042 8192000000 10049685696 10438311936 1093638912 11.777 100 85 154 0 1167662384 58354224 15669248
11:28:55 1405 8192000000 10052772016 10439929856 1085031376 11.695 100 88 158 1 1175869664 74106096 16732192
11:29:57 1163 8192000000 10052946944 10438201344 1087556528 11.719 100 96 181 2 1170812576 64568560 18687488
11:30:59 1009 8192000000 10046544864 10432659456 1075793408 11.607 100 89 170 3 1174049152 80441344 17814400
11:32:01 1216 8192000000 10021220016 10403848192 1057670384 11.434 100 93 167 3 1139766768 64663088 17433296
11:33:03 969 8192000000 10026589104 10409263104 1050283344 11.363 100 93 175 0 1147401696 78635328 18483024
11:34:05 1197 8192000000 9998083536 10369101824 1027712432 11.146 100 90 170 2 1121281072 76244528 17324112
11:35:07 1280 8192000000 10011813200 10386112512 1022202592 11.093 100 99 192 0 1135556080 94697904 18655584
11:36:09 2772 8192000000 9971698656 10358280192 1033274672 11.200 100 61 115 3 1097271712 52410416 11586624
11:37:13 1156 8192000000 9975593664 10348068864 1038252496 11.248 99 93 179 2 1095975680 39290096 18433088
11:38:18 3383 8192000000 10048285088 10427412480 1064969888 11.504 100 83 155 2 1165525776 85515392 15040496
11:39:33 3142 8192000000 10056429664 10441838592 1077329344 11.622 100 76 145 1 1178888320 87908736 13650240
11:40:40 1044 8192000000 10087293952 10468687872 1089192048 11.735 100 97 193 2 1206755472 97808656 19754768
11:41:44 1446 8192000000 10101267840 10489536512 1098610832 11.824 100 88 170 0 1220059664 103141280 18307552
11:42:50 7459 8192000000 10125394880 10513424384 1111882960 11.950 100 74 144 1 1237132144 110413760 14835424
11:44:21 13553 8192000000 10182942528 10584936448 1131143824 12.132 100 59 115 1 1287240784 142550752 13546208
11:45:37 984 8192000000 10244481936 10639753216 1150363936 12.313 100 87 166 2 1360140448 190573776 19202736
11:46:42 1317 8192000000 10307583904 10704908288 1158184352 12.386 100 94 184 1 1421358976 240564000 22610624
11:47:48 2950 8192000000 10309858592 10727837696 1165809664 12.458 100 90 172 1 1420757024 235284672 19662688
11:49:04 1185 8192000000 10360713584 10758053888 1178837152 12.579 100 94 182 0 1468126976 268290736 20999088
11:50:09 819 8192000000 10424805008 10826764288 1196481328 12.744 100 100 185 0 1534147232 314021312 23644592
11:51:12 1109 8192000000 10467435360 10885578752 1202696032 12.801 100 95 182 0 1576479504 352895056 20888416
11:52:18 1345 8192000000 10478960144 10885828608 1200075392 12.777 100 91 173 0 1587246928 366323792 20847744
11:53:23 1501 8192000000 10512178880 10939191296 1207243984 12.844 100 92 179 2 1619105424 391646304 20215136
11:54:29 1020 8192000000 10523930352 10945961984 1206619808 12.838 100 90 180 1 1634828592 405893536 22315248
11:55:33 832 8192000000 10576994224 11007463424 1202428432 12.799 100 88 168 1 1682096032 457951360 21716240
11:56:37 965 8192000000 10571297216 11027521536 1191264064 12.695 99 97 194 0 1679633456 465115280 23254112
11:57:40 3142 8192000000 10546526848 11026452480 1204301216 12.816 100 63 126 1 1655005424 434404128 16300080
11:58:57 8128 8192000000 10564999776 11006820352 1183334000 12.621 100 53 106 2 1672058240 474666608 14057632
12:00:39 5154 8192000000 10475464448 10943111168 1175229312 12.546 100 53 102 1 1586112320 397438064 13444944
12:01:47 23654 8192000000 10405931536 10884952064 1154603648 12.353 100 29 58 0 1519043248 356451168 7988432
12:03:13 24937 8192000000 10335663552 10861797376 1154082560 12.348 100 22 44 0 1429705344 270036848 5585936
12:04:47 2785 8192000000 10199527792 10684805120 1138993152 12.206 100 57 107 5 1305454640 153945200 12516288
12:05:50 28221 8192000000 10151439856 10588094464 1151160064 12.320 100 26 49 1 1248831216 91809632 5861520
12:07:23 25267 8192000000 10216355024 10662600704 1149266784 12.303 100 26 52 0 1307593696 152409536 5917376
12:08:49 72339 8192000000 10172920512 10615689216 1143126064 12.245 99 25 50 0 1271646176 122961872 5558240
12:11:02 50819 8192000000 10086067616 10584678400 1126416720 12.088 98 14 28 1 1194001584 64370768 3214096
12:12:57 144327 8192000000 10106443552 10577678336 1039034464 11.255 90 17 33 0 1098425008 56214624 3175920
12:16:26 84573 8192000000 10087317936 10551803904 1098418112 11.823 97 8 15 0 1144116544 44330768 1367664
12:19:17 98920 8192000000 10079504176 10563620864 1078865952 11.637 96 8 15 0 1126422960 45627264 1929744
12:21:58 32777 8192000000 10068231744 10493075456 1101303696 11.850 98 41 78 2 1153758576 45092128 7362752
12:23:35 43429 8192000000 10045729712 10500280320 1089728608 11.740 97 19 38 0 1124088784 30681152 3679024
12:25:20 116711 8192000000 10080696912 10505326592 1079543008 11.643 96 23 46 0 1123808384 39400608 4864768
12:28:33 51992 8192000000 10096588688 10558623744 1117723792 12.005 99 43 83 1 1222381664 95497616 9160256
12:30:35 17381 8192000000 10100667536 10527358976 1126794048 12.091 99 48 95 0 1189779632 52849792 10135792
12:31:56 33149 8192000000 10123805344 10547412992 1125783456 12.082 99 49 88 0 1198379680 64177776 8418448
12:33:30 5946 8192000000 10082033552 10514784256 1132558464 12.145 100 50 81 0 1191308352 49253792 9496096
12:34:40 20575 8192000000 10077846448 10512027648 1121913248 12.045 98 33 64 0 1166468992 36751552 7804192
12:36:03 72911 8192000000 10103678464 10565042176 1139423680 12.210 99 15 30 0 1183337584 40962464 2951440
12:38:21 48393 8192000000 10086776912 10557427712 1137570864 12.193 100 15 30 1 1199837168 59415952 2850352
12:40:13 86507 8192000000 10053877552 10536038400 1084111136 11.687 95 19 38 0 1118427728 30413936 3902656
12:42:40 72789 8192000000 10087702720 10538156032 1102869616 11.865 97 22 43 1 1145203408 37270512 5063280
12:45:01 52773 8192000000 10094087824 10563104768 1115469792 11.984 98 37 72 2 1197557600 74466624 7621184
12:46:56 32534 8192000000 10145324784 10581987328 1138565008 12.202 100 36 72 0 1230552496 84296000 7691488
12:48:30 38807 8192000000 10161887392 10630643712 1132392032 12.144 99 33 65 0 1247041920 107699424 6950464
12:50:22 33961 8192000000 10184488816 10655838208 1147467904 12.286 100 24 48 0 1274242112 121325408 5448800
12:52:00 93893 8192000000 10117248560 10600722432 1089310416 11.736 96 15 28 0 1208724224 115485312 3928496
12:54:35 96643 8192000000 10134220976 10616668160 1101268144 11.850 98 31 62 1 1216294944 108581712 6445088
12:57:22 26006 8192000000 10073069248 10567950336 1123748816 12.062 100 25 50 0 1199747904 70763136 5235952
12:58:51 48716 8192000000 10060219952 10551848960 1097155552 11.811 98 11 22 0 1170925648 70677264 3092832
13:00:40 52746 8192000000 10066418736 10541383680 1105090624 11.886 99 20 40 0 1166643856 56879936 4673296
13:02:45 52210 8192000000 10018185856 10448199680 1086242736 11.707 98 17 35 1 1109138416 19634480 3261200
13:04:44 98078 8192000000 10077250000 10534830080 1108497392 11.918 99 16 32 0 1161833136 49302080 4033664
13:07:54 51327 8192000000 10059001312 10538377216 1098910112 11.827 98 18 36 0 1183175408 80014608 4250688
13:09:51 36814 8192000000 10126234512 10556481536 1127377024 12.097 100 44 85 0 1222525392 86447536 8700832
13:11:56 48174 8192000000 10102328272 10538360832 1107618176 11.910 98 26 52 0 1181550976 68430528 5502272
13:13:51 23320 8192000000 10118553600 10553085952 1106401808 11.898 97 54 106 0 1204012848 86671056 10939984
13:15:14 30676 8192000000 10136665056 10583457792 1122372368 12.049 99 38 76 0 1215241344 85236240 7632736
13:16:47 32134 8192000000 10148884256 10578796544 1135277856 12.171 100 36 71 0 1235876080 92883104 7715120
13:18:20 73191 8192000000 10122092576 10598891520 1114868384 11.978 98 34 66 1 1205277568 83419488 6989696
13:20:38 28072 8192000000 10074430272 10502180864 1117706912 12.005 99 49 92 1 1167324704 39153616 10464176
13:22:07 35760 8192000000 10089694416 10518544384 1125663376 12.080 100 62 124 1 1186496416 47652912 13180128
13:23:50 3017 8192000000 10069625904 10502295552 1137008000 12.187 100 66 123 2 1172126320 21312256 13806064
13:24:54 64051 8192000000 10082601792 10550226944 1146014528 12.272 100 9 18 0 1190595648 42577040 2004080
13:27:00 44246 8192000000 10066260176 10535469056 1121904752 12.045 98 16 31 0 1172933984 47904112 3125120
13:29:19 85100 8192000000 10062595312 10545086464 1087582224 11.720 96 12 24 0 1117764768 27841072 2341472
13:31:52 11359 8192000000 10085606224 10514071552 1130162624 12.123 100 55 111 0 1170695344 29417264 11115456
13:33:04 11754 8192000000 10070361264 10498240512 1106524880 11.900 98 49 96 0 1161144336 45488528 9130928
13:34:22 54330 8192000000 10072914736 10551095296 1102641360 11.863 98 25 48 1 1195298768 87536896 5120512
13:36:22 30610 8192000000 10068682512 10499211264 1111952112 11.951 99 36 70 1 1157352016 38690736 6709168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-12-2022 13:38:27 to 21-12-2022 13:38:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 70 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-12-2022 13:38:44 to 21-12-2022 13:38:46.

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          215716
MON$OLDEST_ACTIVE               218838
MON$OLDEST_SNAPSHOT             218838
MON$NEXT_TRANSACTION            218838
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                       2054304
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:304: 
:305: mscs-test-161.budzdorov.ru    Wed Dec 21 13:37:29 2022
:306:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:307: 
--- end of diff output ---

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

21.12.22 13:38:47. 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-21 13:38:50 No severe PSQL-related problems occured
21.12.22 13:38:51. End of report.