Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.03.2023 19: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 15323448 kB
MemAvailable 30417384 kB
Buffers 0 kB
Cached 16370424 kB
SwapCached 3044 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 2023-03-02 19:38:46
DTS_END                         2023-03-02 19:38:46.2300
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(57:17)
IP                              ::1/33592
TRN_ID                          239971
ATT_ID                          654
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: 9091.99 17488 1091039
customer order: creation 175.43 367 21051
customer order: refuse 15.16 354 1819
order to supplier: creation 47.24 1225 5669
order to supplier: removal 3.48 97345 417
invoice (draft): creation 46.60 3242 5592
invoice (draft): removal 50.25 852 6030
invoice accept: apply 42.32 16992 5078
invoice accept: cancel 45.89 829 5507
customer reserve: creation 3868.86 169 464263
customer reserve: removal 4504.78 59 540574
realization accept: apply 85.62 184 10274
realization accept: cancel 74.47 118 8936
payment from customer: creation 59.73 109 7168
payment from customer: removal 5.41 83 649
payment to supplier: creation 50.58 114 6069
payment to supplier: removal 3.44 78 413
service: total inventory turnovers 4.63 1396 556
service: total monetary turnovers 7.13 35 856
service: aggregate perf. data 0.95 19740 114
service: refresh index statistics 0.03 206466 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-03-2023 19:39:45 to 02-03-2023 19:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6557
TEST_TIME 2 5810
TEST_TIME 3 5970
TEST_TIME 4 5670
TEST_TIME 5 5605
TEST_TIME 6 5387
TEST_TIME 7 5303
TEST_TIME 8 5343
TEST_TIME 9 5885
TEST_TIME 10 6240
TEST_TIME 11 6117
TEST_TIME 12 6452
TEST_TIME 13 6405
TEST_TIME 14 6889
TEST_TIME 15 7342
TEST_TIME 16 7604
TEST_TIME 17 7841
TEST_TIME 18 8662
TEST_TIME 19 8676
TEST_TIME 20 8650
TEST_TIME 21 8650
TEST_TIME 22 8738
TEST_TIME 23 8695
TEST_TIME 24 9248
TEST_TIME 25 9394
TEST_TIME 26 9526
TEST_TIME 27 9924
TEST_TIME 28 9851
TEST_TIME 29 10390
TEST_TIME 30 11130
TEST_TIME 31 11333
TEST_TIME 32 11244
TEST_TIME 33 11003
TEST_TIME 34 10714
TEST_TIME 35 10796
TEST_TIME 36 10566
TEST_TIME 37 10400
TEST_TIME 38 10288
TEST_TIME 39 10148
TEST_TIME 40 10019
TEST_TIME 41 10054
TEST_TIME 42 10025
TEST_TIME 43 9942
TEST_TIME 44 9941
TEST_TIME 45 9783
TEST_TIME 46 9847
TEST_TIME 47 9682
TEST_TIME 48 9588
TEST_TIME 49 9612
TEST_TIME 50 9540
TEST_TIME 51 9605
TEST_TIME 52 9490
TEST_TIME 53 9406
TEST_TIME 54 9412
TEST_TIME 55 9380
TEST_TIME 56 9307
TEST_TIME 57 9275
TEST_TIME 58 9154
TEST_TIME 59 9152
TEST_TIME 60 9121
TEST_TIME 61 9139
TEST_TIME 62 9124
TEST_TIME 63 9132
TEST_TIME 64 9100
TEST_TIME 65 9104
TEST_TIME 66 9191
TEST_TIME 67 9168
TEST_TIME 68 9269
TEST_TIME 69 9210
TEST_TIME 70 9236
TEST_TIME 71 9302
TEST_TIME 72 9277
TEST_TIME 73 9393
TEST_TIME 74 9352
TEST_TIME 75 9261
TEST_TIME 76 9211
TEST_TIME 77 9152
TEST_TIME 78 9087
TEST_TIME 79 9190
TEST_TIME 80 9179
TEST_TIME 81 9118
TEST_TIME 82 9155
TEST_TIME 83 9110
TEST_TIME 84 9030
TEST_TIME 85 9046
TEST_TIME 86 9019
TEST_TIME 87 8979
TEST_TIME 88 8944
TEST_TIME 89 8960
TEST_TIME 90 8991
TEST_TIME 91 9045
TEST_TIME 92 9072
TEST_TIME 93 9115
TEST_TIME 94 9103
TEST_TIME 95 9143
TEST_TIME 96 9171
TEST_TIME 97 9208
TEST_TIME 98 9254
TEST_TIME 99 9249
TEST_TIME 100 9304
TEST_TIME 101 9272
TEST_TIME 102 9312
TEST_TIME 103 9284
TEST_TIME 104 9211
TEST_TIME 105 9207
TEST_TIME 106 9175
TEST_TIME 107 9158
TEST_TIME 108 9129
TEST_TIME 109 9146
TEST_TIME 110 9125
TEST_TIME 111 9070
TEST_TIME 112 9042
TEST_TIME 113 9089
TEST_TIME 114 9055
TEST_TIME 115 9074
TEST_TIME 116 9082
TEST_TIME 117 9126
TEST_TIME 118 9119
TEST_TIME 119 9109
TEST_TIME 120 9093

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-03-2023 19:39:48 to 02-03-2023 19:39:52.

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 1521258 1521258 0 0.00 0 19341 6 0 0
doc_list_biud 1521256 1521256 0 0.00 0 39132 27 0 0
sp_add_invoice_to_stock 5213 5078 135 2.58 39 140093 16992 0 135
sp_cancel_adding_invoice 5507 5507 0 0.00 5 48100 830 0 0
sp_cancel_client_order 1819 1819 0 0.00 4 12630 355 0 0
sp_cancel_customer_reserve 540574 540574 0 0.00 0 38209 60 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 4244 83 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 4562 79 0 0
sp_cancel_supplier_invoice 6044 6030 14 0.23 2 39141 853 0 14
sp_cancel_supplier_order 417 417 0 0.00 6 1195976 97346 0 0
sp_cancel_write_off 8936 8936 0 0.00 1 14235 119 0 0
sp_client_order 21051 21051 0 0.00 3 32711 368 0 0
sp_customer_reserve 464263 464263 0 0.00 0 60671 170 0 0
sp_fill_shopping_cart 32299 32299 0 0.00 1 28779 45 0 0
sp_fill_shopping_cart_clo_res 464261 464261 0 0.00 0 13033 14 0 0
sp_get_clo_for_invoice 5076 5076 0 0.00 0 28559 356 0 0
sp_kill_cost_storno 15505 15505 0 0.00 0 12903 57 0 0
sp_kill_qstorno_ret_qs2qd 1096531 1096531 0 0.00 0 39128 32 0 0
sp_kill_qty_storno 578633 578633 0 0.00 0 39131 71 0 0
sp_lock_selected_doc 21239 21239 0 0.00 0 7574 6 0 0
sp_make_cost_storno 28587 28587 0 0.00 0 19319 51 0 0
sp_multiply_rows_for_pdistr 28586 28586 0 0.00 0 10545 26 0 0
sp_multiply_rows_for_qdistr 21051 21051 0 0.00 0 12967 149 0 0
sp_pay_from_customer 7168 7168 0 0.00 4 12159 110 0 0
sp_pay_to_supplier 6069 6069 0 0.00 4 12907 115 0 0
sp_payment_common 13236 13236 0 0.00 1 7023 22 0 0
sp_qd_handle_on_cancel_clo 1819 1819 0 0.00 0 12620 327 0 0
sp_qd_handle_on_invoice_upd_sts 10583 10583 0 0.00 0 23180 334 0 0
sp_qd_handle_on_reserve_upd_sts 10274 10274 0 0.00 0 31377 72 0 0
sp_reserve_write_off 10274 10274 0 0.00 6 31396 184 0 0
sp_supplier_invoice 5592 5592 0 0.00 5 77881 3242 0 0
sp_supplier_order 5669 5669 0 0.00 5 51372 1226 0 0
srv_aggregate_perf_data 114 114 0 0.00 2241 195304 19741 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1465 120787 43977 0 0
srv_find_qd_qs_mism 1042802 1042802 0 0.00 0 15770 5 0 0
srv_make_invnt_saldo 556 556 0 0.00 23 105149 1396 0 0
srv_make_money_saldo 856 856 0 0.00 1 7093 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 93605 333970 206467 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5078 5078 0 0.00 0 7926 147 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 6452 93 0 0
t$perf-norm:sp_cancel_client_order 1819 1819 0 0.00 0 11819 20 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 2304 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 6505 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 374 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 692 18 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 23415 1438 0 0
t$perf-norm:sp_cancel_write_off 1062 1062 0 0.00 0 2943 7 0 0
t$perf-norm:sp_client_order 21051 21051 0 0.00 0 9103 16 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 9546 16 0 0
t$perf-norm:sp_pay_from_customer 7168 7168 0 0.00 0 5449 9 0 0
t$perf-norm:sp_pay_to_supplier 6069 6069 0 0.00 0 7797 11 0 0
t$perf-norm:sp_reserve_write_off 10274 10274 0 0.00 0 12420 13 0 0
t$perf-norm:sp_supplier_invoice 5592 5592 0 0.00 0 6531 17 0 0
t$perf-norm:sp_supplier_order 5669 5669 0 0.00 0 6176 16 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 188 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 313 8 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 2871 25 0 0
t$perf-norm:srv_make_money_saldo 856 856 0 0.00 0 920 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10420 10150 270 2.59 0 5390 20 0 270
v_all_customers 38486 38486 0 0.00 0 21 1 0 0
v_all_suppliers 31266 31266 0 0.00 0 251 1 0 0
v_all_wares 42102 42102 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 2312 9 0 0
v_cancel_client_order 3638 3638 0 0.00 0 3244 5 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 4237 7 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 528 5 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 1478 5 0 0
v_cancel_supplier_invoice 832 804 28 3.36 0 1246 16 0 28
v_cancel_supplier_order 834 834 0 0.00 0 2569 10 0 0
v_cancel_write_off 2124 2124 0 0.00 0 3060 16 0 0
v_max_id_clo_ord 5666 5666 0 0.00 0 296 1 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 1708 8 0 0
v_max_id_ord_sup 5581 5581 0 0.00 0 6278 7 0 0
v_max_non_paid_invoice 1682 1682 0 0.00 0 611 3 0 0
v_max_non_paid_realizn 3783 3783 0 0.00 0 502 3 0 0
v_min_id_clo_ord 5666 5666 0 0.00 0 12765 7 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 4528 13 0 0
v_min_id_ord_sup 5581 5581 0 0.00 0 4748 8 0 0
v_min_non_paid_invoice 6068 6068 0 0.00 0 4833 13 0 0
v_min_non_paid_realizn 7168 7168 0 0.00 0 5772 17 0 0
v_our_firm 10386 10386 0 0.00 0 25 1 0 0
v_reserve_write_off 20548 20548 0 0.00 0 7055 9 0 0
x_lock_depdocs_on_canc_invoice 11135 11135 0 0.00 0 11512 114 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 11392 342 0 0
x_make_qstorno_1000_1200 5669 5669 0 0.00 1 51350 1145 0 0
x_make_qstorno_1200_2000 5591 5591 0 0.00 2 67027 3072 0 0
x_make_qstorno_2100_3300 464263 464263 0 0.00 0 60637 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-03-2023 19:39:53 to 02-03-2023 19:39:57.

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
17:08:50 12 8192000000 8820450896 8839147520 20994672 0.255 8 4 5 0 21226704 92768 139264
17:09:51 24622 8192000000 9912384720 10224230400 991669840 10.798 100 82 164 0 1019100704 6267824 21163040
17:11:21 23254 8192000000 9959346080 10286088192 1045482512 11.317 100 89 178 1 1076535088 8563584 22488992
17:12:48 10574 8192000000 9995507120 10331586560 1080187552 11.649 100 84 168 0 1110534752 9672096 20675104
17:14:04 12449 8192000000 10030263680 10375569408 1117256336 12.001 100 83 165 0 1148635328 11881808 19497184
17:15:31 5055 8192000000 10085870560 10441576448 1169475856 12.492 100 86 172 0 1202496080 13502064 19518160
17:16:39 2815 8192000000 10121840528 10487492608 1206337984 12.835 100 81 162 1 1239158720 14570000 18250736
17:17:43 2432 8192000000 10152282672 10530009088 1246315376 13.204 100 79 154 1 1276594880 12679056 17600448
17:18:48 1219 8192000000 10181814832 10564653056 1275824512 13.475 100 82 163 0 1306089680 12146768 18118400
17:19:51 1995 8192000000 10204409824 10588717056 1289981520 13.604 100 82 164 1 1320085984 12821984 17282480
17:20:55 13414 8192000000 10205252400 10605756416 1303527664 13.727 100 61 121 0 1325864432 8655136 13681632
17:22:10 5703 8192000000 10204757792 10610716672 1309107456 13.778 100 40 84 0 1324961216 6715376 9138384
17:23:16 9520 8192000000 10195498816 10610241536 1311127680 13.796 100 14 28 0 1319734080 5878416 2727984
17:24:28 41894 8192000000 10226542448 10631798784 1318111904 13.860 100 54 108 1 1337393712 6334272 12947536
17:26:11 6060 8192000000 10217617344 10632077312 1321705024 13.892 100 39 79 1 1340575648 10197648 8672976
17:27:20 8165 8192000000 10209695312 10631979008 1323416448 13.908 100 27 51 0 1334881072 5987824 5476800
17:28:30 36334 8192000000 10213986720 10625921024 1306579104 13.755 99 46 92 1 1322695872 5312144 10804624
17:30:12 9475 8192000000 10184232256 10592026624 1279102736 13.505 98 32 65 2 1292439696 5337168 7999792
17:31:22 9043 8192000000 10116651648 10512535552 1176580096 12.558 94 13 25 0 1184477696 4374496 3523104
17:32:31 40912 8192000000 10141544608 10536431616 1179366016 12.584 93 37 73 1 1193533280 5343920 8823344
17:34:34 7696 8192000000 10160595568 10567168000 1260537632 13.335 99 32 63 1 1273641680 5364640 7739408
17:35:42 9066 8192000000 10168950016 10581082112 1285294464 13.561 100 23 46 0 1296009632 5338752 5376416
17:36:55 13611 8192000000 10187513808 10593554432 1291949456 13.622 100 16 32 0 1301570304 5257952 4362896
17:38:10 37419 8192000000 10201871344 10617843712 1308926016 13.776 100 26 52 0 1320517312 5088480 6502816
17:39:54 26690 8192000000 10253543632 10665639936 1344266016 14.096 100 50 98 0 1366396208 11098512 11031680
17:41:24 14084 8192000000 10278346192 10691899392 1362388512 14.259 100 55 110 2 1390988240 15641632 12958096
17:42:41 33391 8192000000 10285157856 10709278720 1372431488 14.349 100 35 68 0 1397524320 16799168 8293664
17:44:19 12108 8192000000 10296818272 10725793792 1385631152 14.467 100 35 70 1 1411001872 16923904 8446816
17:45:44 7466 8192000000 10314501904 10741280768 1396651344 14.565 100 47 94 1 1428047392 19964688 11431360
17:46:55 26177 8192000000 10333491424 10761633792 1406869232 14.656 100 38 74 1 1440040736 23938640 9232864
17:48:30 5540 8192000000 10348960960 10775064576 1418957040 14.763 100 56 112 2 1460848384 29114848 12776496
17:49:42 12628 8192000000 10337038304 10761994240 1410430896 14.688 100 50 98 0 1449707088 27221184 12055008
17:50:59 4412 8192000000 10339678928 10762915840 1407181904 14.659 100 48 95 2 1445972816 27684880 11106032
17:52:07 14276 8192000000 10332730144 10748780544 1379988736 14.416 98 53 105 2 1429073792 35906016 13179040
17:53:27 8452 8192000000 10322777200 10741006336 1378750672 14.405 100 53 104 2 1430805600 38578832 13476096
17:54:41 16295 8192000000 10326051872 10751488000 1390374624 14.509 100 54 102 1 1436838176 33275744 13187808
17:56:09 3558 8192000000 10323527216 10733649920 1359101184 14.229 99 71 142 0 1416419504 40149648 17168672
17:57:17 5735 8192000000 10335186528 10747899904 1372911360 14.353 99 66 128 5 1421943648 33533472 15498816
17:58:26 3416 8192000000 10316479504 10721611776 1357612080 14.216 99 61 119 1 1410335840 37338880 15384880
17:59:34 1534 8192000000 10348600944 10750865408 1380563392 14.422 100 82 161 3 1447991424 46223472 21204560
18:00:43 5552 8192000000 10338464640 10743439360 1358996544 14.228 98 59 119 0 1429135552 54255344 15883664
18:01:54 1465 8192000000 10367278656 10772533248 1388171072 14.490 100 72 143 0 1465659584 58296384 19192128
18:03:05 4856 8192000000 10331115344 10745683968 1372536320 14.350 100 51 100 2 1433210960 47155712 13518928
18:04:14 19377 8192000000 10308430432 10744131584 1363253536 14.267 100 28 57 0 1420960928 49110720 8596672
18:05:42 16632 8192000000 10320486640 10759680000 1371325984 14.339 100 22 44 0 1424434448 46990480 6117984
18:07:01 23000 8192000000 10319887104 10757591040 1354653712 14.189 100 25 50 0 1422445232 60447584 7343936
18:08:59 5729 8192000000 10295610320 10725863424 1362917744 14.264 99 45 85 1 1394497632 21575552 10004336
18:10:06 28697 8192000000 10305380224 10747314176 1387196432 14.481 100 30 60 1 1406546320 12457216 6892672
18:11:47 115222 8192000000 10297943520 10758307840 1358985024 14.228 98 14 28 0 1381268512 19200944 3082544
18:14:51 33729 8192000000 10307276656 10747514880 1388413296 14.492 100 42 79 0 1411979008 14787408 8778304
18:16:26 53354 8192000000 10313946256 10758787072 1392114800 14.525 100 52 106 0 1420698640 15930096 12653744
18:18:21 31265 8192000000 10328514944 10763874304 1394496976 14.546 100 36 73 0 1423445344 20046080 8902288
18:19:57 75529 8192000000 10325272400 10772811776 1397397872 14.572 100 46 90 4 1424122864 15871536 10853456
18:22:20 42638 8192000000 10297866800 10763894784 1368173952 14.311 98 13 25 0 1394105632 23146048 2785632
18:24:13 80426 8192000000 10287208080 10745008128 1386546304 14.475 100 10 20 0 1399773568 11034816 2192448
18:26:34 86108 8192000000 10294909840 10756206592 1384082704 14.453 99 41 82 0 1403010048 9212784 9714560
18:29:01 89290 8192000000 10299210608 10764578816 1377949744 14.398 98 10 20 0 1391701648 11827904 1924000
18:31:35 68412 8192000000 10309240096 10776649728 1409324208 14.678 100 18 36 0 1422964752 9324560 4315984
18:33:44 52391 8192000000 10298937216 10754576384 1371410976 14.340 97 22 42 0 1384245216 7988016 4846224
18:35:41 87109 8192000000 10262330672 10729472000 1340991584 14.066 97 16 32 1 1353548608 8708432 3848592
18:38:32 86274 8192000000 10286678320 10746970112 1363049280 14.265 98 11 23 0 1379547328 14015536 2482512
18:40:58 44282 8192000000 10278299152 10738262016 1350428128 14.151 97 16 32 0 1361591104 7776576 3386400
18:42:48 41992 8192000000 10289092880 10738339840 1330673568 13.973 96 29 58 0 1346625632 9872096 6079968
18:44:33 36742 8192000000 10276526112 10727915520 1331032272 13.976 96 19 39 1 1349541744 13867760 4641712
18:46:10 66943 8192000000 10278168976 10728071168 1311214656 13.797 95 12 25 0 1326325568 12443232 2667680
18:48:17 42742 8192000000 10274311120 10723463168 1357675824 14.216 99 20 40 0 1372445040 10342480 4426736
18:50:00 11617 8192000000 10269689344 10698539008 1341796480 14.074 100 41 82 0 1360300256 9478352 9025424
18:51:14 120787 8192000000 10258449792 10717007872 1280691056 13.519 94 8 16 0 1302561584 20390864 1479664
18:54:35 102145 8192000000 10248367120 10686418944 1282123072 13.532 94 20 40 0 1296527296 10418464 3985760
18:57:18 50139 8192000000 10245742416 10714492928 1338400880 14.043 99 26 52 0 1361287456 16069728 6816848
18:59:18 60409 8192000000 10269252352 10719985664 1365591152 14.288 100 20 38 0 1377916720 8289232 4036336
19:01:19 56151 8192000000 10253577056 10708410368 1333035840 13.995 97 15 30 0 1342375136 6178816 3160480
19:03:29 51223 8192000000 10277202272 10731716608 1374303200 14.366 100 12 22 0 1385155232 8282544 2569488
19:05:21 66041 8192000000 10297328928 10755928064 1367490192 14.305 99 5 9 1 1375770544 7601936 678416
19:08:08 66536 8192000000 10303287984 10756763648 1372093904 14.346 98 23 45 1 1392368256 14808304 5466048
19:10:30 91504 8192000000 10305480048 10766585856 1381827152 14.433 98 16 32 0 1395051856 9465776 3758928
19:13:02 41979 8192000000 10312219328 10766987264 1386802816 14.477 99 30 60 0 1408061584 14694640 6564128
19:14:45 90971 8192000000 10324254144 10779414528 1389656752 14.503 99 16 32 0 1407158352 13851328 3650272
19:17:16 27542 8192000000 10313414384 10759745536 1384311296 14.455 99 30 61 0 1400755920 9948752 6495872
19:18:49 34858 8192000000 10320283568 10762788864 1381178496 14.427 98 38 74 2 1397510528 7370432 8961600
19:20:31 82638 8192000000 10296680288 10762555392 1363549680 14.269 97 18 36 0 1382227744 14699760 3978304
19:23:26 48547 8192000000 10289454416 10755727360 1382726128 14.441 99 16 32 0 1395850720 10139824 2984768
19:25:16 62626 8192000000 10272481488 10736431104 1337772224 14.037 96 10 20 0 1347127584 7404448 1950912
19:27:21 64583 8192000000 10275230000 10735316992 1346435024 14.115 97 14 25 1 1361070960 11901312 2734624
19:29:32 83892 8192000000 10273236064 10733076480 1367869840 14.308 98 20 40 0 1382938640 10613904 4454896
19:32:04 82976 8192000000 10291567392 10750115840 1360212784 14.239 98 10 18 0 1374877920 12549536 2115600
19:34:28 48646 8192000000 10268875040 10724159488 1339641344 14.054 97 21 42 0 1357764464 13582224 4540896
19:36:17 43069 8192000000 10253102688 10704072704 1328363216 13.952 98 21 42 0 1342521984 9778480 4380288
19:38:13 40610 8192000000 9022202784 9229848576 188778000 2.252 14 3 6 0 196152304 6642656 731648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-03-2023 19:39:59 to 02-03-2023 19:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-03-2023 19:40:15 to 02-03-2023 19:40:17.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240324
MON$OLDEST_ACTIVE               240325
MON$OLDEST_SNAPSHOT             240325
MON$NEXT_TRANSACTION            240325
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-03 16:41:55.3470
MON$PAGES                       2028960
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:887: 
:888: mscs-test-161.budzdorov.ru    Thu Mar  2 19:38:58 2023
:889:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:890: 
--- end of diff output ---

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

02.03.23 19:40:18. There are 3 logs in /var/tmp/logs-oltp30 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
2023-03-02 19:40:21 No severe PSQL-related problems occured
02.03.23 19:40:22. End of report.