Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.01.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 15240324 kB
MemAvailable 30380744 kB
Buffers 0 kB
Cached 16444952 kB
SwapCached 4424 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-01-12 19:38:34
DTS_END                         2023-01-12 19:38:34.6580
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/44404
TRN_ID                          243537
ATT_ID                          677
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: 9250.89 18118 1110107
customer order: creation 174.25 366 20910
customer order: refuse 14.74 360 1769
order to supplier: creation 45.14 1322 5417
order to supplier: removal 3.42 95361 410
invoice (draft): creation 45.80 3363 5496
invoice (draft): removal 53.57 773 6428
invoice accept: apply 42.01 17340 5041
invoice accept: cancel 47.56 795 5707
customer reserve: creation 3826.00 173 459120
customer reserve: removal 4704.26 54 564511
realization accept: apply 82.54 220 9905
realization accept: cancel 82.89 111 9947
payment from customer: creation 58.97 121 7076
payment from customer: removal 5.84 90 701
payment to supplier: creation 48.80 149 5856
payment to supplier: removal 3.48 114 418
service: total inventory turnovers 4.38 1225 525
service: total monetary turnovers 6.43 37 772
service: aggregate perf. data 0.78 22448 94
service: refresh index statistics 0.03 217936 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-01-2023 19:39:28 to 12-01-2023 19:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3768
TEST_TIME 2 3677
TEST_TIME 3 2753
TEST_TIME 4 2863
TEST_TIME 5 3417
TEST_TIME 6 4290
TEST_TIME 7 5120
TEST_TIME 8 5517
TEST_TIME 9 6098
TEST_TIME 10 6295
TEST_TIME 11 6495
TEST_TIME 12 6801
TEST_TIME 13 7014
TEST_TIME 14 7491
TEST_TIME 15 7575
TEST_TIME 16 7603
TEST_TIME 17 7928
TEST_TIME 18 8093
TEST_TIME 19 8028
TEST_TIME 20 8469
TEST_TIME 21 8801
TEST_TIME 22 8664
TEST_TIME 23 9117
TEST_TIME 24 9178
TEST_TIME 25 9329
TEST_TIME 26 9523
TEST_TIME 27 9697
TEST_TIME 28 10046
TEST_TIME 29 10493
TEST_TIME 30 10942
TEST_TIME 31 10733
TEST_TIME 32 10518
TEST_TIME 33 10555
TEST_TIME 34 10615
TEST_TIME 35 10480
TEST_TIME 36 10366
TEST_TIME 37 10251
TEST_TIME 38 10192
TEST_TIME 39 10098
TEST_TIME 40 9972
TEST_TIME 41 10034
TEST_TIME 42 9928
TEST_TIME 43 9861
TEST_TIME 44 9721
TEST_TIME 45 9550
TEST_TIME 46 9671
TEST_TIME 47 9566
TEST_TIME 48 9488
TEST_TIME 49 9379
TEST_TIME 50 9469
TEST_TIME 51 9454
TEST_TIME 52 9380
TEST_TIME 53 9406
TEST_TIME 54 9322
TEST_TIME 55 9236
TEST_TIME 56 9105
TEST_TIME 57 9135
TEST_TIME 58 9136
TEST_TIME 59 9215
TEST_TIME 60 9150
TEST_TIME 61 9103
TEST_TIME 62 9248
TEST_TIME 63 9274
TEST_TIME 64 9214
TEST_TIME 65 9261
TEST_TIME 66 9365
TEST_TIME 67 9338
TEST_TIME 68 9406
TEST_TIME 69 9383
TEST_TIME 70 9463
TEST_TIME 71 9487
TEST_TIME 72 9490
TEST_TIME 73 9558
TEST_TIME 74 9450
TEST_TIME 75 9390
TEST_TIME 76 9371
TEST_TIME 77 9305
TEST_TIME 78 9257
TEST_TIME 79 9312
TEST_TIME 80 9296
TEST_TIME 81 9241
TEST_TIME 82 9199
TEST_TIME 83 9215
TEST_TIME 84 9225
TEST_TIME 85 9221
TEST_TIME 86 9244
TEST_TIME 87 9265
TEST_TIME 88 9269
TEST_TIME 89 9256
TEST_TIME 90 9289
TEST_TIME 91 9322
TEST_TIME 92 9348
TEST_TIME 93 9369
TEST_TIME 94 9375
TEST_TIME 95 9437
TEST_TIME 96 9409
TEST_TIME 97 9488
TEST_TIME 98 9512
TEST_TIME 99 9551
TEST_TIME 100 9463
TEST_TIME 101 9427
TEST_TIME 102 9381
TEST_TIME 103 9391
TEST_TIME 104 9380
TEST_TIME 105 9328
TEST_TIME 106 9334
TEST_TIME 107 9310
TEST_TIME 108 9273
TEST_TIME 109 9230
TEST_TIME 110 9233
TEST_TIME 111 9194
TEST_TIME 112 9133
TEST_TIME 113 9157
TEST_TIME 114 9193
TEST_TIME 115 9190
TEST_TIME 116 9265
TEST_TIME 117 9269
TEST_TIME 118 9260
TEST_TIME 119 9293
TEST_TIME 120 9257

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-01-2023 19:39:32 to 12-01-2023 19:39:35.

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 1536141 1536141 0 0.00 0 31102 6 0 0
doc_list_biud 1536141 1536141 0 0.00 0 64074 26 0 0
sp_add_invoice_to_stock 5148 5041 107 2.07 19 162732 17341 0 107
sp_cancel_adding_invoice 5707 5707 0 0.00 3 63982 795 0 0
sp_cancel_client_order 1769 1769 0 0.00 4 14391 360 0 0
sp_cancel_customer_reserve 564511 564511 0 0.00 0 64075 54 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 2 7207 90 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 5713 115 0 0
sp_cancel_supplier_invoice 6438 6428 10 0.15 1 29887 773 0 10
sp_cancel_supplier_order 410 410 0 0.00 8 901521 95361 0 0
sp_cancel_write_off 9947 9947 0 0.00 1 17114 111 0 0
sp_client_order 20910 20910 0 0.00 3 43864 367 0 0
sp_customer_reserve 459120 459120 0 0.00 0 58423 173 0 0
sp_fill_shopping_cart 31809 31809 0 0.00 1 12620 40 0 0
sp_fill_shopping_cart_clo_res 459113 459113 0 0.00 0 24618 14 0 0
sp_get_clo_for_invoice 5034 5034 0 0.00 0 33542 357 0 0
sp_kill_cost_storno 16773 16773 0 0.00 0 31098 58 0 0
sp_kill_qstorno_ret_qs2qd 1145807 1145807 0 0.00 0 64071 30 0 0
sp_kill_qty_storno 603711 603711 0 0.00 0 64074 65 0 0
sp_lock_selected_doc 20748 20748 0 0.00 0 8126 6 0 0
sp_make_cost_storno 27871 27871 0 0.00 0 14155 60 0 0
sp_multiply_rows_for_pdistr 27871 27871 0 0.00 0 14912 28 0 0
sp_multiply_rows_for_qdistr 20910 20910 0 0.00 0 43854 167 0 0
sp_pay_from_customer 7076 7076 0 0.00 4 16335 121 0 0
sp_pay_to_supplier 5856 5856 0 0.00 4 22286 149 0 0
sp_payment_common 12932 12932 0 0.00 1 16704 40 0 0
sp_qd_handle_on_cancel_clo 1769 1769 0 0.00 0 14365 328 0 0
sp_qd_handle_on_invoice_upd_sts 10741 10741 0 0.00 0 26700 330 0 0
sp_qd_handle_on_reserve_upd_sts 9905 9905 0 0.00 0 20702 78 0 0
sp_reserve_write_off 9905 9905 0 0.00 6 20713 221 0 0
sp_supplier_invoice 5496 5496 0 0.00 5 74516 3363 0 0
sp_supplier_order 5417 5417 0 0.00 5 36682 1323 0 0
srv_aggregate_perf_data 94 94 0 0.00 2678 374963 22448 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1518 129793 46119 0 0
srv_find_qd_qs_mism 1062906 1062906 0 0.00 0 20612 5 0 0
srv_make_invnt_saldo 525 525 0 0.00 41 98325 1225 0 0
srv_make_money_saldo 772 772 0 0.00 0 6905 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 149010 330137 217937 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5042 5042 0 0.00 0 10039 184 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 8552 135 0 0
t$perf-norm:sp_cancel_client_order 1769 1769 0 0.00 0 1169 12 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 1348 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 1877 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 3975 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 816 14 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 23098 1670 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 2820 8 0 0
t$perf-norm:sp_client_order 20910 20910 0 0.00 0 6974 19 0 0
t$perf-norm:sp_customer_reserve 1030 1030 0 0.00 0 3535 19 0 0
t$perf-norm:sp_pay_from_customer 7076 7076 0 0.00 0 9405 15 0 0
t$perf-norm:sp_pay_to_supplier 5856 5856 0 0.00 0 5084 11 0 0
t$perf-norm:sp_reserve_write_off 9905 9905 0 0.00 0 9038 13 0 0
t$perf-norm:sp_supplier_invoice 5496 5496 0 0.00 0 11093 24 0 0
t$perf-norm:sp_supplier_order 5417 5417 0 0.00 0 9987 24 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 176 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 6796 109 0 0
t$perf-norm:srv_make_invnt_saldo 525 525 0 0.00 0 2817 29 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 4474 33 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 10282 10068 214 2.08 0 11433 20 0 214
v_all_customers 37842 37842 0 0.00 0 18 1 0 0
v_all_suppliers 30182 30182 0 0.00 0 26 1 0 0
v_all_wares 41820 41820 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 3236 10 0 0
v_cancel_client_order 3538 3538 0 0.00 0 2641 6 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 7176 9 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 7054 14 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 4677 13 0 0
v_cancel_supplier_invoice 826 806 20 2.42 0 4877 19 0 20
v_cancel_supplier_order 820 820 0 0.00 0 2001 8 0 0
v_cancel_write_off 2020 2020 0 0.00 0 8437 28 0 0
v_max_id_clo_ord 5417 5417 0 0.00 0 2823 2 0 0
v_max_id_clo_res 1030 1030 0 0.00 0 2757 16 0 0
v_max_id_ord_sup 5482 5482 0 0.00 0 4303 4 0 0
v_max_non_paid_invoice 1664 1664 0 0.00 0 88 2 0 0
v_max_non_paid_realizn 3870 3870 0 0.00 0 2262 4 0 0
v_min_id_clo_ord 5417 5417 0 0.00 0 7006 11 0 0
v_min_id_clo_res 1030 1030 0 0.00 0 4883 24 0 0
v_min_id_ord_sup 5482 5482 0 0.00 0 7793 8 0 0
v_min_non_paid_invoice 5856 5856 0 0.00 0 16690 38 0 0
v_min_non_paid_realizn 7076 7076 0 0.00 0 16056 25 0 0
v_our_firm 10390 10390 0 0.00 0 21 1 0 0
v_reserve_write_off 19810 19810 0 0.00 0 4839 8 0 0
x_lock_depdocs_on_canc_invoice 11732 11732 0 0.00 0 15312 123 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 7727 373 0 0
x_make_qstorno_1000_1200 5417 5417 0 0.00 2 30313 1229 0 0
x_make_qstorno_1200_2000 5496 5496 0 0.00 2 74371 3223 0 0
x_make_qstorno_2100_3300 459120 459120 0 0.00 0 54108 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-01-2023 19:39:36 to 12-01-2023 19:39:40.

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:38 2 8192000000 8802243200 8814628864 7394416 0.090 3 1 2 0 7495376 58176 42784
17:09:40 27778 8192000000 9911000080 10221551616 985525568 10.738 100 86 172 0 1014944064 6455904 22962592
17:11:15 15700 8192000000 9938512304 10264129536 1025167376 11.122 100 86 172 0 1056057440 8527568 22362496
17:12:39 15541 8192000000 9992005376 10326605824 1073457584 11.585 100 86 171 1 1104762688 9490800 21814304
17:14:03 7300 8192000000 10032234848 10382880768 1122445696 12.050 100 78 157 0 1152508528 11342816 18720016
17:15:16 5265 8192000000 10083593120 10443415552 1170374912 12.500 100 70 138 1 1196921520 10597280 15949328
17:16:25 2761 8192000000 10140995776 10509873152 1222262640 12.983 100 85 169 0 1254315600 13598096 18454864
17:17:29 4859 8192000000 10165005728 10549026816 1256901664 13.302 100 68 134 0 1284294704 12011216 15381824
17:18:36 866 8192000000 10207388464 10587865088 1288988480 13.595 100 97 194 2 1324906064 14263024 21654560
17:19:38 3123 8192000000 10201641840 10602250240 1307127504 13.760 100 52 104 1 1330021072 11190672 11702896
17:20:42 5576 8192000000 10195205120 10609053696 1312856432 13.812 100 35 71 1 1328728672 7456000 8416240
17:21:48 11425 8192000000 10206183136 10627211264 1321122672 13.887 100 46 92 2 1337038384 5803648 10112064
17:23:00 5556 8192000000 10222779072 10641571840 1329886128 13.966 100 33 66 0 1343567936 6269808 7412000
17:24:07 5923 8192000000 10223728480 10641682432 1334895504 14.011 100 23 45 1 1345454528 5750640 4808384
17:25:15 10269 8192000000 10229141936 10649849856 1338880432 14.047 100 11 22 0 1346700688 5464960 2355296
17:26:29 17946 8192000000 10227753952 10654740480 1340084800 14.058 100 21 42 0 1350516096 5682080 4749216
17:27:47 7779 8192000000 10214037024 10633392128 1305738592 13.747 98 23 46 0 1316950528 6003568 5208368
17:28:59 6301 8192000000 10175542976 10580885504 1236938592 13.118 95 21 42 1 1247486768 5165408 5382768
17:30:08 60295 8192000000 10148511952 10543128576 1176826992 12.561 93 22 43 0 1186321136 4769776 4724368
17:32:10 3563 8192000000 10126249024 10526085120 1236629104 13.115 100 44 87 1 1253956384 7114400 10212880
17:33:14 6648 8192000000 10162760864 10562256896 1265538144 13.381 100 27 54 1 1277559648 5680672 6340832
17:34:21 5402 8192000000 10183624032 10585001984 1283212176 13.542 100 28 54 0 1295577664 6160048 6205440
17:35:29 30362 8192000000 10178354976 10592362496 1290840240 13.612 100 32 63 2 1303201824 4980896 7380688
17:36:59 5198 8192000000 10190561328 10602094592 1299662352 13.692 100 32 65 0 1313318880 5314576 8341952
17:38:10 8442 8192000000 10214340240 10623590400 1315411936 13.835 100 14 28 0 1323022928 4508288 3102704
17:39:21 17896 8192000000 10237776800 10650537984 1327044544 13.940 99 34 66 1 1340691536 6105136 7541856
17:40:46 28535 8192000000 10261992336 10678046720 1347017296 14.121 99 44 87 2 1367620864 10846080 9757488
17:42:23 44000 8192000000 10269003088 10696515584 1365879120 14.290 100 34 68 0 1388740032 14960736 7900176
17:44:16 16264 8192000000 10279178752 10702315520 1371124800 14.337 100 37 74 0 1397113568 17212608 8776160
17:45:38 11051 8192000000 10298998320 10717659136 1382003872 14.434 100 62 118 4 1410846448 14750704 14091872
17:46:54 12712 8192000000 10321726992 10739367936 1399512848 14.591 100 60 117 0 1431844800 18609312 13722640
17:48:16 9718 8192000000 10325386512 10744422400 1402063616 14.613 100 62 120 1 1435465680 19068352 14333712
17:49:30 5159 8192000000 10325880896 10743185408 1396520176 14.564 100 60 120 0 1431817744 20643136 14654432
17:50:39 7243 8192000000 10329633568 10748612608 1383741488 14.450 98 66 129 0 1421555632 21376304 16437840
17:51:53 6912 8192000000 10320117600 10728386560 1362326768 14.258 98 71 139 1 1406187088 26494512 17365808
17:53:03 5709 8192000000 10308146784 10714107904 1365022928 14.282 99 78 155 0 1409575456 25934048 18618480
17:54:15 2708 8192000000 10282346336 10683924480 1328588048 13.954 97 79 151 0 1376754304 29223632 18942624
17:55:28 1517 8192000000 10311076688 10712379392 1358087056 14.220 99 85 167 3 1407771760 29056720 20627984
17:56:38 4803 8192000000 10320515376 10741571584 1380714400 14.423 100 50 101 0 1428638544 34822128 13102016
17:57:52 5470 8192000000 10345174368 10757083136 1379210752 14.409 99 74 147 0 1438843216 40804432 18828032
17:59:01 9148 8192000000 10309871024 10732257280 1357184128 14.212 98 45 90 1 1412319344 42849456 12285760
18:00:14 6207 8192000000 10296936208 10711965696 1356208704 14.203 100 43 86 0 1401666336 34363424 11094208
18:01:24 22635 8192000000 10311464336 10744508416 1346788928 14.119 98 29 58 0 1396840928 41984352 8067648
18:02:50 11375 8192000000 10313923472 10742796288 1367971808 14.309 100 27 54 0 1416669920 41022944 7675168
18:04:04 11804 8192000000 10306750672 10737700864 1364865248 14.281 99 30 60 0 1412951824 40106496 7980080
18:05:28 25919 8192000000 10307492384 10742697984 1358555936 14.224 99 21 42 0 1403992032 39291568 6144528
18:06:56 29761 8192000000 10289637648 10732392448 1346904864 14.120 100 18 36 1 1385323408 33488496 4930048
18:08:42 78868 8192000000 10274350640 10704326656 1334901792 14.011 98 39 76 1 1367179008 23065872 9211344
18:11:15 39693 8192000000 10275349632 10727006208 1373437200 14.358 100 11 22 0 1390640320 14919296 2283824
18:12:57 60009 8192000000 10281803216 10741579776 1380900992 14.425 100 7 14 0 1393296144 10941152 1454000
18:15:04 83454 8192000000 10285282256 10731986944 1354270112 14.186 97 20 40 0 1367526384 8582480 4673792
18:17:28 49159 8192000000 10301889872 10746818560 1390472336 14.510 100 21 44 0 1403459632 8089088 4898208
18:19:18 58014 8192000000 10311093408 10765418496 1399844368 14.594 100 22 44 0 1418300160 13515296 4940496
18:21:21 58804 8192000000 10295225616 10754199552 1395548608 14.555 100 14 28 0 1410506192 11857248 3100336
18:23:24 103619 8192000000 10294351760 10748657664 1386859632 14.478 99 33 63 1 1412381328 17842496 7679200
18:26:18 6127 8192000000 10302593536 10749353984 1398208112 14.579 100 48 96 0 1417630128 7773920 11648096
18:27:27 49146 8192000000 10307421696 10769985536 1392132784 14.525 99 32 64 1 1410466880 10621552 7712544
18:29:30 59079 8192000000 10288630784 10753019904 1383981808 14.452 99 13 26 0 1400609328 13696240 2931280
18:31:38 48041 8192000000 10281322768 10736545792 1354752848 14.190 97 8 16 0 1363944384 7242224 1949312
18:34:29 82466 8192000000 10278929696 10745966592 1367611008 14.306 98 15 30 1 1383239968 12232448 3396512
18:36:51 63991 8192000000 10282031296 10757455872 1361049472 14.247 97 12 24 0 1375525344 11867280 2608592
18:39:04 71953 8192000000 10294694224 10757496832 1356560528 14.206 97 13 26 0 1373563392 14263152 2739712
18:41:23 79581 8192000000 10288821024 10736300032 1312553840 13.809 94 22 44 1 1326662640 9403120 4705680
18:43:47 13583 8192000000 10303039440 10750550016 1388179152 14.490 100 47 94 0 1410741760 11645872 10916736
18:45:02 62753 8192000000 10282339216 10729353216 1297008928 13.668 94 13 26 0 1315726128 15909840 2807360
18:47:05 129793 8192000000 10256326016 10714398720 1258992976 13.321 92 7 14 0 1277694992 17282864 1419152
18:50:18 48656 8192000000 10266825808 10716581888 1320501728 13.881 97 24 46 0 1345538192 20041360 4995104
18:52:08 72577 8192000000 10246454304 10685009920 1314452896 13.826 97 9 18 0 1335734272 19223104 2058272
18:54:32 75731 8192000000 10239497472 10676301824 1326254016 13.933 99 23 46 0 1339637072 8125040 5258016
18:56:49 42409 8192000000 10240798336 10687987712 1333611776 14.000 99 46 92 0 1356733696 12932352 10189568
18:58:37 76118 8192000000 10262651472 10706640896 1344005744 14.094 99 15 29 0 1360805392 12652816 4146832
19:01:52 39282 8192000000 10252043856 10717298688 1358131408 14.221 100 8 16 0 1373480544 13455136 1894000
19:03:39 56033 8192000000 10262739328 10716418048 1356002784 14.201 100 27 52 1 1375110704 13293568 5814352
19:05:35 63491 8192000000 10267249904 10716098560 1333853168 14.002 98 15 30 0 1347104064 9770512 3480384
19:07:40 69789 8192000000 10270533648 10718375936 1335403792 14.016 97 14 28 0 1349699712 10972976 3322944
19:09:51 66434 8192000000 10289281968 10735579136 1360494208 14.242 98 13 26 0 1373756848 10330704 2931936
19:11:58 52418 8192000000 10297966784 10753126400 1373747920 14.361 99 24 48 0 1393022432 14087216 5187296
19:13:51 41909 8192000000 10305809664 10745270272 1363228064 14.266 98 39 78 0 1385368112 13177872 8962176
19:15:40 98776 8192000000 10306425424 10759331840 1361707920 14.253 98 20 40 0 1378931008 13170320 4052768
19:18:20 89087 8192000000 10284348368 10731270144 1352277104 14.168 98 14 28 0 1367052256 11617072 3158080
19:20:59 67571 8192000000 10277948448 10738810880 1359112208 14.229 99 22 44 0 1381461008 17659024 4689776
19:23:08 100814 8192000000 10286126416 10749497344 1387186032 14.481 100 9 18 1 1401409488 11873520 2349936
19:25:51 66044 8192000000 10270178512 10728058880 1342360608 14.079 96 20 40 1 1354511824 7554192 4597024
19:29:18 56804 8192000000 10269782288 10726682624 1354388144 14.187 98 10 20 0 1366743696 10389344 1966208
19:31:21 55285 8192000000 10279031120 10739900416 1354724576 14.190 98 13 26 0 1372937984 14418592 3794816
19:33:22 90119 8192000000 10281918032 10752012288 1354352720 14.187 98 10 20 0 1372000832 15452784 2195328
19:35:53 32479 8192000000 10261784640 10709569536 1329268784 13.961 98 36 71 0 1348402384 11545968 7587632
19:37:31 48853 8192000000 10264923168 10704580608 1326996800 13.940 98 18 36 0 1339125008 8088944 4039264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-01-2023 19:39:42 to 12-01-2023 19:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-01-2023 19:39:58 to 12-01-2023 19:40:00.

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          242823
MON$OLDEST_ACTIVE               244290
MON$OLDEST_SNAPSHOT             244290
MON$NEXT_TRANSACTION            244290
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                       2038304
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.33653 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33653 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33653 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 ---
:87: 
:88: mscs-test-161.budzdorov.ru    Thu Jan 12 19:38:37 2023
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:90: 
:91: 
:92: mscs-test-161.budzdorov.ru    Thu Jan 12 19:38:43 2023
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:94: 
:95: 
:96: mscs-test-161.budzdorov.ru    Thu Jan 12 19:38:43 2023
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:98: 
--- end of diff output ---

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

12.01.23 19:40:01. 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-01-12 19:40:04 No severe PSQL-related problems occured
12.01.23 19:40:05. End of report.