Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.02.2023 16:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15148708 kB
MemAvailable 30376776 kB
Buffers 0 kB
Cached 16532528 kB
SwapCached 3564 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-02-05 16:38:37
DTS_END                         2023-02-05 16:38:37.8190
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/42280
TRN_ID                          246973
ATT_ID                          709
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: 9494.18 22924 1139302
customer order: creation 175.97 367 21116
customer order: refuse 15.38 323 1845
order to supplier: creation 47.04 1215 5645
order to supplier: removal 3.64 96065 437
invoice (draft): creation 47.88 3173 5746
invoice (draft): removal 54.11 748 6493
invoice accept: apply 42.49 17008 5099
invoice accept: cancel 48.33 777 5800
customer reserve: creation 3945.28 166 473433
customer reserve: removal 4807.82 58 576938
realization accept: apply 85.27 208 10232
realization accept: cancel 87.60 104 10512
payment from customer: creation 61.48 152 7377
payment from customer: removal 6.00 58 720
payment to supplier: creation 50.64 165 6077
payment to supplier: removal 3.48 65 418
service: total inventory turnovers 4.66 1042 559
service: total monetary turnovers 6.29 28 755
service: aggregate perf. data 0.80 23756 96
service: refresh index statistics 0.03 313003 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-02-2023 16:39:15 to 05-02-2023 16:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2983
TEST_TIME 2 2419
TEST_TIME 3 3658
TEST_TIME 4 3976
TEST_TIME 5 4863
TEST_TIME 6 5079
TEST_TIME 7 4949
TEST_TIME 8 5117
TEST_TIME 9 5355
TEST_TIME 10 5314
TEST_TIME 11 5306
TEST_TIME 12 5366
TEST_TIME 13 5419
TEST_TIME 14 5487
TEST_TIME 15 5791
TEST_TIME 16 5666
TEST_TIME 17 6114
TEST_TIME 18 6378
TEST_TIME 19 7085
TEST_TIME 20 7480
TEST_TIME 21 7803
TEST_TIME 22 8042
TEST_TIME 23 8078
TEST_TIME 24 8439
TEST_TIME 25 8619
TEST_TIME 26 8914
TEST_TIME 27 9188
TEST_TIME 28 9414
TEST_TIME 29 9873
TEST_TIME 30 10382
TEST_TIME 31 10324
TEST_TIME 32 10245
TEST_TIME 33 10302
TEST_TIME 34 10702
TEST_TIME 35 10611
TEST_TIME 36 10729
TEST_TIME 37 10578
TEST_TIME 38 10336
TEST_TIME 39 10134
TEST_TIME 40 10072
TEST_TIME 41 9954
TEST_TIME 42 10028
TEST_TIME 43 9886
TEST_TIME 44 9842
TEST_TIME 45 9783
TEST_TIME 46 9720
TEST_TIME 47 9952
TEST_TIME 48 9814
TEST_TIME 49 9713
TEST_TIME 50 9653
TEST_TIME 51 9486
TEST_TIME 52 9521
TEST_TIME 53 9499
TEST_TIME 54 9428
TEST_TIME 55 9433
TEST_TIME 56 9358
TEST_TIME 57 9325
TEST_TIME 58 9295
TEST_TIME 59 9198
TEST_TIME 60 9140
TEST_TIME 61 9156
TEST_TIME 62 9137
TEST_TIME 63 9127
TEST_TIME 64 9187
TEST_TIME 65 9195
TEST_TIME 66 9224
TEST_TIME 67 9246
TEST_TIME 68 9249
TEST_TIME 69 9266
TEST_TIME 70 9342
TEST_TIME 71 9390
TEST_TIME 72 9379
TEST_TIME 73 9406
TEST_TIME 74 9493
TEST_TIME 75 9512
TEST_TIME 76 9510
TEST_TIME 77 9403
TEST_TIME 78 9436
TEST_TIME 79 9349
TEST_TIME 80 9344
TEST_TIME 81 9331
TEST_TIME 82 9278
TEST_TIME 83 9291
TEST_TIME 84 9217
TEST_TIME 85 9257
TEST_TIME 86 9266
TEST_TIME 87 9251
TEST_TIME 88 9263
TEST_TIME 89 9326
TEST_TIME 90 9319
TEST_TIME 91 9338
TEST_TIME 92 9415
TEST_TIME 93 9430
TEST_TIME 94 9475
TEST_TIME 95 9504
TEST_TIME 96 9518
TEST_TIME 97 9550
TEST_TIME 98 9525
TEST_TIME 99 9563
TEST_TIME 100 9472
TEST_TIME 101 9508
TEST_TIME 102 9462
TEST_TIME 103 9445
TEST_TIME 104 9414
TEST_TIME 105 9366
TEST_TIME 106 9317
TEST_TIME 107 9341
TEST_TIME 108 9314
TEST_TIME 109 9309
TEST_TIME 110 9369
TEST_TIME 111 9354
TEST_TIME 112 9366
TEST_TIME 113 9374
TEST_TIME 114 9464
TEST_TIME 115 9461
TEST_TIME 116 9487
TEST_TIME 117 9458
TEST_TIME 118 9474
TEST_TIME 119 9517
TEST_TIME 120 9497

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-02-2023 16:39:18 to 05-02-2023 16:39:22.

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 1578837 1578837 0 0.00 0 39904 6 0 0
doc_list_biud 1578836 1578836 0 0.00 0 28968 27 0 0
sp_add_invoice_to_stock 5237 5099 138 2.63 21 205927 17009 0 138
sp_cancel_adding_invoice 5800 5800 0 0.00 5 99625 777 0 0
sp_cancel_client_order 1845 1845 0 0.00 4 7928 323 0 0
sp_cancel_customer_reserve 576938 576938 0 0.00 0 28972 58 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 2 3348 59 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 2 4955 66 0 0
sp_cancel_supplier_invoice 6501 6493 8 0.12 1 17227 748 0 8
sp_cancel_supplier_order 437 437 0 0.00 5 1063074 96066 0 0
sp_cancel_write_off 10512 10512 0 0.00 1 14686 105 0 0
sp_client_order 21116 21116 0 0.00 3 18995 368 0 0
sp_customer_reserve 473433 473433 0 0.00 0 35153 167 0 0
sp_fill_shopping_cart 32503 32503 0 0.00 1 17195 42 0 0
sp_fill_shopping_cart_clo_res 473431 473431 0 0.00 0 16572 13 0 0
sp_get_clo_for_invoice 5097 5097 0 0.00 0 19395 306 0 0
sp_kill_cost_storno 17450 17450 0 0.00 0 17692 54 0 0
sp_kill_qstorno_ret_qs2qd 1171318 1171318 0 0.00 0 28948 31 0 0
sp_kill_qty_storno 617354 617354 0 0.00 0 28965 67 0 0
sp_lock_selected_doc 21389 21389 0 0.00 0 7044 5 0 0
sp_make_cost_storno 28783 28783 0 0.00 0 36767 81 0 0
sp_multiply_rows_for_pdistr 28783 28783 0 0.00 0 38151 39 0 0
sp_multiply_rows_for_qdistr 21116 21116 0 0.00 0 9155 160 0 0
sp_pay_from_customer 7377 7377 0 0.00 3 38195 153 0 0
sp_pay_to_supplier 6077 6077 0 0.00 5 38461 166 0 0
sp_payment_common 13454 13454 0 0.00 2 36624 49 0 0
sp_qd_handle_on_cancel_clo 1845 1845 0 0.00 0 7914 298 0 0
sp_qd_handle_on_invoice_upd_sts 10897 10897 0 0.00 0 14193 304 0 0
sp_qd_handle_on_reserve_upd_sts 10232 10232 0 0.00 0 11456 55 0 0
sp_reserve_write_off 10232 10232 0 0.00 6 39938 209 0 0
sp_supplier_invoice 5746 5746 0 0.00 7 85334 3174 0 0
sp_supplier_order 5645 5645 0 0.00 5 19959 1215 0 0
srv_aggregate_perf_data 96 96 0 0.00 2718 430722 23757 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1271 163214 41812 0 0
srv_find_qd_qs_mism 1090802 1090802 0 0.00 0 10269 5 0 0
srv_make_invnt_saldo 559 559 0 0.00 32 33975 1042 0 0
srv_make_money_saldo 755 755 0 0.00 0 1066 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 253906 396332 313004 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5100 5100 0 0.00 0 18294 167 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 2672 88 0 0
t$perf-norm:sp_cancel_client_order 1845 1845 0 0.00 0 4265 12 0 0
t$perf-norm:sp_cancel_customer_reserve 736 736 0 0.00 0 1080 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 3238 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 693 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 447 447 0 0.00 0 2157 24 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 23804 1621 0 0
t$perf-norm:sp_cancel_write_off 1055 1055 0 0.00 0 1775 7 0 0
t$perf-norm:sp_client_order 21116 21116 0 0.00 0 10546 15 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 298 2 0 0
t$perf-norm:sp_pay_from_customer 7377 7377 0 0.00 0 9914 12 0 0
t$perf-norm:sp_pay_to_supplier 6077 6077 0 0.00 0 11473 15 0 0
t$perf-norm:sp_reserve_write_off 10232 10232 0 0.00 0 7210 14 0 0
t$perf-norm:sp_supplier_invoice 5746 5746 0 0.00 0 5034 18 0 0
t$perf-norm:sp_supplier_order 5645 5645 0 0.00 0 12547 20 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 357 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 414 9 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 2089 23 0 0
t$perf-norm:srv_make_money_saldo 755 755 0 0.00 0 3726 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10470 10194 276 2.63 0 8180 23 0 276
v_all_customers 38692 38692 0 0.00 0 18 1 0 0
v_all_suppliers 31854 31854 0 0.00 0 29 1 0 0
v_all_wares 42230 42230 0 0.00 0 67 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 4898 15 0 0
v_cancel_client_order 3690 3690 0 0.00 0 5389 6 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 974 4 0 0
v_cancel_customer_reserve 1472 1472 0 0.00 0 1175 7 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 1120 5 0 0
v_cancel_supplier_invoice 910 894 16 1.75 0 4546 25 0 16
v_cancel_supplier_order 874 874 0 0.00 0 386 3 0 0
v_cancel_write_off 2110 2110 0 0.00 0 2453 14 0 0
v_max_id_clo_ord 5645 5645 0 0.00 0 2710 2 0 0
v_max_id_clo_res 997 997 0 0.00 0 2400 19 0 0
v_max_id_ord_sup 5743 5743 0 0.00 0 2603 3 0 0
v_max_non_paid_invoice 1538 1538 0 0.00 0 469 3 0 0
v_max_non_paid_realizn 3862 3862 0 0.00 0 4093 7 0 0
v_min_id_clo_ord 5645 5645 0 0.00 0 3845 8 0 0
v_min_id_clo_res 997 997 0 0.00 0 1693 5 0 0
v_min_id_ord_sup 5743 5743 0 0.00 0 5404 10 0 0
v_min_non_paid_invoice 6077 6077 0 0.00 0 35898 36 0 0
v_min_non_paid_realizn 7377 7377 0 0.00 0 35899 41 0 0
v_our_firm 10568 10568 0 0.00 0 19 1 0 0
v_reserve_write_off 20464 20464 0 0.00 0 8607 7 0 0
x_lock_depdocs_on_canc_invoice 11846 11846 0 0.00 0 12310 112 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 2663 284 0 0
x_make_qstorno_1000_1200 5645 5645 0 0.00 1 19808 1137 0 0
x_make_qstorno_1200_2000 5746 5746 0 0.00 3 84627 3016 0 0
x_make_qstorno_2100_3300 473433 473433 0 0.00 0 35151 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-02-2023 16:39:23 to 05-02-2023 16:39:27.

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
14:08:41 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:43 29948 8192000000 9901817296 10213498880 985808976 10.741 100 87 174 0 1015294912 6502736 22983200
14:11:19 22164 8192000000 9943985776 10265313280 1024520832 11.116 100 85 170 0 1055425488 9006192 21898464
14:12:49 12837 8192000000 9977922944 10311884800 1061096400 11.467 100 84 168 0 1092624240 10374128 21153712
14:14:05 12979 8192000000 10038569968 10385801216 1122275632 12.048 100 83 166 0 1152972112 10990512 19705968
14:15:24 8759 8192000000 10082523376 10439376896 1166758768 12.467 100 86 172 0 1199023008 12618704 19645536
14:16:36 2439 8192000000 10124872208 10490892288 1213643232 12.903 100 83 166 0 1244989552 12796816 18549504
14:17:42 1930 8192000000 10153130896 10529452032 1243505888 13.179 100 84 164 0 1275050016 12897840 18646288
14:18:46 1604 8192000000 10180559792 10564608000 1271249136 13.433 100 81 162 0 1302013264 12978064 17786064
14:19:49 43352 8192000000 10194635616 10594025472 1294563168 13.646 100 44 88 0 1312868464 7829216 10476080
14:21:33 8966 8192000000 10192200720 10603839488 1304563728 13.737 100 28 57 0 1317456272 6675984 6216560
14:22:44 13068 8192000000 10205734928 10623635456 1319128560 13.869 100 11 22 0 1330562864 9404464 2029840
14:23:57 73518 8192000000 10218707872 10640920576 1328624480 13.955 100 24 49 0 1339273968 4895200 5754288
14:26:15 8846 8192000000 10239717952 10654793728 1335400016 14.016 100 25 50 0 1350927856 9887920 5639920
14:27:25 8925 8192000000 10226564960 10649284608 1326741776 13.938 99 21 42 0 1337477824 5941712 4794336
14:28:35 17512 8192000000 10205462032 10615660544 1259004816 13.321 95 34 71 1 1272913616 6318048 7590752
14:29:54 16835 8192000000 10188167680 10602864640 1262467888 13.353 96 33 62 1 1275022704 5757120 6797696
14:31:12 6333 8192000000 10138524176 10535354368 1194909520 12.729 95 20 40 0 1204752112 5351728 4490864
14:32:22 8821 8192000000 10145824736 10541453312 1193629936 12.717 95 17 31 0 1205950000 8767296 3552768
14:33:36 6425 8192000000 10153963936 10559729664 1265080336 13.377 100 22 45 0 1276048848 5799792 5168720
14:34:45 14359 8192000000 10157241008 10563375104 1265901696 13.384 99 39 78 2 1285956752 12027200 8027856
14:36:00 3342 8192000000 10175956608 10588590080 1287495184 13.581 100 21 42 0 1297393104 4921008 4976912
14:37:04 9398 8192000000 10196040112 10609381376 1301179792 13.706 100 25 50 0 1313016384 5700672 6135920
14:38:14 11259 8192000000 10218510384 10634686464 1327915200 13.948 100 18 35 1 1337670944 5710640 4045104
14:39:33 36432 8192000000 10256244832 10668482560 1351190800 14.158 100 47 93 0 1369653376 7155616 11306960
14:41:11 39118 8192000000 10261328656 10680827904 1359153936 14.230 100 35 70 0 1376103792 9290560 7659296
14:43:01 16398 8192000000 10282802896 10704031744 1375054240 14.372 100 37 74 0 1393522608 10477408 7990960
14:44:33 7835 8192000000 10300476816 10724216832 1389784080 14.504 100 60 118 3 1415484368 12517072 13183216
14:45:47 9491 8192000000 10311227680 10732863488 1395681152 14.557 100 59 118 1 1424003216 14424048 13898016
14:47:02 10327 8192000000 10322499280 10748010496 1409104112 14.676 100 48 94 1 1435652928 15348352 11200464
14:48:26 19595 8192000000 10328195488 10755940352 1412296336 14.704 100 47 94 0 1443688736 20096784 11295616
14:49:54 12564 8192000000 10323937456 10752335872 1397708608 14.575 99 39 78 0 1428539072 20995008 9835456
14:51:17 5890 8192000000 10334243088 10761510912 1412534256 14.706 100 52 100 0 1449599920 24463456 12602208
14:52:29 5020 8192000000 10339252496 10756296704 1387850112 14.487 97 59 116 0 1431192640 28324736 15017792
14:53:43 7730 8192000000 10347743728 10774560768 1409228480 14.677 100 57 112 2 1464103328 39588784 15286064
14:54:57 4680 8192000000 10335001456 10748145664 1387183232 14.481 100 67 131 1 1442910512 38628464 17098816
14:56:06 8235 8192000000 10300956656 10711130112 1349704960 14.145 100 61 120 1 1409846160 43942032 16199168
14:57:19 2021 8192000000 10323033088 10718347264 1356636336 14.207 100 89 175 3 1423222624 44761520 21824768
14:58:27 1265 8192000000 10320689952 10722291712 1364735040 14.280 100 78 155 3 1423690160 39300592 19654528
14:59:34 2623 8192000000 10352536800 10762903552 1389691344 14.503 100 74 148 0 1456289184 48372656 18225184
15:00:40 5322 8192000000 10328484704 10757271552 1384061248 14.453 100 42 85 0 1437386096 41806544 11518304
15:01:50 3365 8192000000 10312273888 10730676224 1352969504 14.174 98 42 85 2 1410381792 46397712 11014576
15:02:56 14358 8192000000 10327408704 10753363968 1374860864 14.371 100 30 57 1 1429313376 46165856 8286656
15:04:13 13734 8192000000 10338881920 10774065152 1390619536 14.511 100 42 81 3 1451969248 50340080 11009632
15:05:45 25807 8192000000 10323198720 10763296768 1383483504 14.448 100 25 50 0 1437228080 46589568 7155008
15:07:37 52320 8192000000 10290654624 10738638848 1350501424 14.152 98 15 30 0 1397355440 43153376 3700640
15:09:30 37516 8192000000 10305081888 10731388928 1354671712 14.189 98 28 57 0 1370431952 9683232 6077008
15:11:13 57693 8192000000 10310320144 10758483968 1363838016 14.272 99 12 24 1 1391544112 24546416 3159680
15:13:16 76655 8192000000 10297803264 10753925120 1367341904 14.303 99 7 14 0 1385082864 16634592 1106368
15:15:42 55401 8192000000 10288207952 10723364864 1357437008 14.214 98 42 87 1 1373928736 6175488 10316240
15:17:49 14281 8192000000 10305455552 10748096512 1393883520 14.541 100 43 86 1 1415847616 11373088 10591008
15:19:04 43436 8192000000 10316041136 10759667712 1403372960 14.625 100 43 87 0 1422888480 8736656 10778864
15:21:01 52265 8192000000 10332612784 10787348480 1393722688 14.539 99 26 54 0 1420212560 20677184 5812688
15:22:55 82113 8192000000 10321674032 10785660928 1413208528 14.712 100 10 19 0 1435477872 19874336 2395008
15:26:09 24324 8192000000 10314591040 10752307200 1392603744 14.529 99 12 24 0 1405343840 10254800 2485296
15:27:34 69940 8192000000 10296744976 10764873728 1400892176 14.603 100 9 16 0 1413155056 10151600 2111280
15:30:14 49904 8192000000 10302750368 10766852096 1407959040 14.666 100 20 38 0 1419884528 7699136 4226352
15:32:14 8226 8192000000 10312894464 10768187392 1412509584 14.706 100 38 76 1 1429802240 8908784 8383872
15:33:28 62540 8192000000 10300777520 10773364736 1398576976 14.582 99 10 20 0 1409367744 8496640 2294128
15:35:30 50256 8192000000 10303630768 10767147008 1407550560 14.662 100 11 22 0 1418401232 8458560 2392112
15:37:38 84842 8192000000 10294387792 10756591616 1355752960 14.199 96 26 52 0 1371412336 9811984 5847392
15:40:05 66349 8192000000 10291264304 10762084352 1389501632 14.501 99 11 22 0 1400128688 8343360 2283696
15:42:11 55911 8192000000 10265025872 10727993344 1310889344 13.794 94 16 32 0 1322612592 8285040 3438208
15:44:08 32568 8192000000 10254392144 10707755008 1339423184 14.052 98 15 30 0 1355525952 12570800 3531968
15:45:41 25694 8192000000 10273069856 10715856896 1355358400 14.196 100 29 58 0 1373143536 11144576 6640560
15:47:12 93357 8192000000 10259967984 10714066944 1296988336 13.668 96 11 22 0 1312343840 13054448 2301056
15:49:49 27946 8192000000 10263613792 10693898240 1314073696 13.823 97 41 81 0 1331367472 8248320 9045456
15:51:22 67923 8192000000 10268940864 10716545024 1328969392 13.958 98 22 42 0 1352594784 18630656 4994736
15:53:40 52706 8192000000 10250615920 10706210816 1315511664 13.836 97 19 38 0 1330948496 11307072 4129760
15:55:33 88497 8192000000 10247080512 10701119488 1310570160 13.791 97 18 36 0 1329443040 14892704 3980176
15:58:03 3785 8192000000 10261391840 10691051520 1331360016 13.979 99 55 110 0 1354267376 11278896 11628464
15:59:40 51161 8192000000 10254330272 10715103232 1362088528 14.256 100 9 17 0 1371316176 7272912 1954736
16:01:32 34324 8192000000 10266255520 10724483072 1365300160 14.285 100 13 24 2 1377365504 9405056 2660288
16:03:07 57811 8192000000 10257795344 10732089344 1360774880 14.244 100 10 20 0 1379769888 16450512 2544496
16:05:12 40787 8192000000 10294010768 10741813248 1381694112 14.432 100 38 76 0 1402801504 12464848 8642544
16:06:53 62105 8192000000 10284405616 10750492672 1384073088 14.453 100 17 34 0 1403400432 15233488 4093856
16:08:56 52243 8192000000 10305803456 10752557056 1372439440 14.349 98 22 44 0 1383823856 6839456 4544960
16:10:50 163214 8192000000 10310919680 10787188736 1348451024 14.134 96 5 11 0 1362803072 13559648 792400
16:14:53 64853 8192000000 10305535904 10769731584 1398214096 14.579 100 15 30 0 1414894048 13266944 3413008
16:16:58 107970 8192000000 10299544736 10761261056 1385843136 14.469 99 13 25 0 1401637872 12912608 2882128
16:19:47 53079 8192000000 10305381440 10776129536 1398948928 14.586 100 25 50 0 1418365760 13760880 5655952
16:21:42 26344 8192000000 10284336800 10746818560 1378162640 14.400 99 16 32 0 1393122224 11724016 3235568
16:23:10 64982 8192000000 10282441216 10740609024 1370412448 14.331 99 25 50 1 1388573600 12139168 6021984
16:25:16 66159 8192000000 10270405392 10734002176 1348540352 14.134 98 11 21 0 1366779760 15748208 2491200
16:27:24 45852 8192000000 10273154688 10747842560 1359038688 14.229 99 5 10 0 1384574304 24410320 1125296
16:29:11 50163 8192000000 10266920608 10720120832 1313991520 13.822 95 17 34 0 1326540480 8534848 4014112
16:31:01 51220 8192000000 10281461904 10737676288 1352532720 14.170 98 21 42 0 1371442224 14108288 4801216
16:32:58 62496 8192000000 10256905600 10696433664 1308800848 13.775 96 16 32 0 1322316368 9885776 3629744
16:35:05 50570 8192000000 10270108480 10709712896 1327067264 13.941 98 35 70 0 1343914752 9038944 7808544
16:36:59 65923 8192000000 10258352080 10707402752 1313314320 13.816 97 30 60 0 1332841504 13279424 6247760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 05-02-2023 16:39:29 to 05-02-2023 16:39:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-02-2023 16:39:46 to 05-02-2023 16:39:48.

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          246127
MON$OLDEST_ACTIVE               247201
MON$OLDEST_SNAPSHOT             247201
MON$NEXT_TRANSACTION            247201
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                       2046816
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.33659 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33659 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33659 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33659 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 ---
:58: 
:59: mscs-test-161.budzdorov.ru    Sun Feb  5 16:38:40 2023
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:61: 
--- end of diff output ---

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

05.02.23 16:39:49. 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-02-05 16:39:51 No severe PSQL-related problems occured
05.02.23 16:39:52. End of report.