Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.02.2023 19:40
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 15357576 kB
MemAvailable 30445636 kB
Buffers 0 kB
Cached 16377432 kB
SwapCached 3352 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-12 19:38:40
DTS_END                         2023-02-12 19:38:40.5040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38712
TRN_ID                          245603
ATT_ID                          650
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: 9193.45 19363 1103214
customer order: creation 181.62 348 21794
customer order: refuse 15.02 337 1802
order to supplier: creation 47.93 1269 5751
order to supplier: removal 3.43 83148 411
invoice (draft): creation 47.42 3368 5690
invoice (draft): removal 51.67 746 6200
invoice accept: apply 42.38 17225 5086
invoice accept: cancel 46.37 733 5564
customer reserve: creation 3910.47 173 469256
customer reserve: removal 4539.92 48 544790
realization accept: apply 88.54 166 10625
realization accept: cancel 82.83 100 9940
payment from customer: creation 62.85 112 7542
payment from customer: removal 5.78 77 694
payment to supplier: creation 51.13 126 6135
payment to supplier: removal 3.53 94 423
service: total inventory turnovers 4.93 1156 592
service: total monetary turnovers 6.83 29 819
service: aggregate perf. data 0.80 18823 96
service: refresh index statistics 0.03 259183 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-02-2023 19:40:38 to 12-02-2023 19:40:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3584
TEST_TIME 2 4012
TEST_TIME 3 3607
TEST_TIME 4 4042
TEST_TIME 5 3696
TEST_TIME 6 4186
TEST_TIME 7 4406
TEST_TIME 8 4518
TEST_TIME 9 4917
TEST_TIME 10 5345
TEST_TIME 11 6012
TEST_TIME 12 6366
TEST_TIME 13 6448
TEST_TIME 14 6975
TEST_TIME 15 7196
TEST_TIME 16 7096
TEST_TIME 17 7476
TEST_TIME 18 7625
TEST_TIME 19 7843
TEST_TIME 20 8579
TEST_TIME 21 8940
TEST_TIME 22 9012
TEST_TIME 23 9496
TEST_TIME 24 9591
TEST_TIME 25 9615
TEST_TIME 26 9888
TEST_TIME 27 10293
TEST_TIME 28 10227
TEST_TIME 29 10063
TEST_TIME 30 9967
TEST_TIME 31 10086
TEST_TIME 32 10162
TEST_TIME 33 10274
TEST_TIME 34 10016
TEST_TIME 35 9893
TEST_TIME 36 9712
TEST_TIME 37 9626
TEST_TIME 38 9596
TEST_TIME 39 9612
TEST_TIME 40 9551
TEST_TIME 41 9553
TEST_TIME 42 9596
TEST_TIME 43 9618
TEST_TIME 44 9690
TEST_TIME 45 9699
TEST_TIME 46 9581
TEST_TIME 47 9578
TEST_TIME 48 9570
TEST_TIME 49 9459
TEST_TIME 50 9367
TEST_TIME 51 9357
TEST_TIME 52 9311
TEST_TIME 53 9201
TEST_TIME 54 9152
TEST_TIME 55 9062
TEST_TIME 56 9247
TEST_TIME 57 9212
TEST_TIME 58 9188
TEST_TIME 59 9151
TEST_TIME 60 9166
TEST_TIME 61 9393
TEST_TIME 62 9432
TEST_TIME 63 9428
TEST_TIME 64 9535
TEST_TIME 65 9558
TEST_TIME 66 9577
TEST_TIME 67 9595
TEST_TIME 68 9612
TEST_TIME 69 9643
TEST_TIME 70 9676
TEST_TIME 71 9614
TEST_TIME 72 9495
TEST_TIME 73 9460
TEST_TIME 74 9435
TEST_TIME 75 9395
TEST_TIME 76 9350
TEST_TIME 77 9377
TEST_TIME 78 9322
TEST_TIME 79 9282
TEST_TIME 80 9239
TEST_TIME 81 9264
TEST_TIME 82 9202
TEST_TIME 83 9134
TEST_TIME 84 9207
TEST_TIME 85 9199
TEST_TIME 86 9256
TEST_TIME 87 9267
TEST_TIME 88 9225
TEST_TIME 89 9231
TEST_TIME 90 9202
TEST_TIME 91 9169
TEST_TIME 92 9255
TEST_TIME 93 9281
TEST_TIME 94 9271
TEST_TIME 95 9254
TEST_TIME 96 9294
TEST_TIME 97 9294
TEST_TIME 98 9227
TEST_TIME 99 9225
TEST_TIME 100 9175
TEST_TIME 101 9188
TEST_TIME 102 9196
TEST_TIME 103 9183
TEST_TIME 104 9165
TEST_TIME 105 9107
TEST_TIME 106 9140
TEST_TIME 107 9106
TEST_TIME 108 9126
TEST_TIME 109 9132
TEST_TIME 110 9157
TEST_TIME 111 9174
TEST_TIME 112 9165
TEST_TIME 113 9248
TEST_TIME 114 9266
TEST_TIME 115 9276
TEST_TIME 116 9306
TEST_TIME 117 9289
TEST_TIME 118 9307
TEST_TIME 119 9251
TEST_TIME 120 9196

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-02-2023 19:40:42 to 12-02-2023 19:40:45.

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 1538304 1538304 0 0.00 0 32386 6 0 0
doc_list_biud 1538301 1538301 0 0.00 0 37239 22 0 0
sp_add_invoice_to_stock 5196 5086 110 2.11 27 203090 17226 0 110
sp_cancel_adding_invoice 5564 5564 0 0.00 5 78575 733 0 0
sp_cancel_client_order 1802 1802 0 0.00 4 8547 338 0 0
sp_cancel_customer_reserve 544790 544790 0 0.00 0 22530 48 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 2 4276 78 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 6140 95 0 0
sp_cancel_supplier_invoice 6215 6200 15 0.24 2 31360 746 0 15
sp_cancel_supplier_order 411 411 0 0.00 5 882632 83148 0 0
sp_cancel_write_off 9940 9940 0 0.00 1 31696 101 0 0
sp_client_order 21794 21794 0 0.00 3 31022 348 0 0
sp_customer_reserve 469256 469256 0 0.00 0 90428 173 0 0
sp_fill_shopping_cart 33218 33218 0 0.00 1 23071 40 0 0
sp_fill_shopping_cart_clo_res 469251 469251 0 0.00 0 13727 13 0 0
sp_get_clo_for_invoice 5081 5081 0 0.00 0 20352 259 0 0
sp_kill_cost_storno 16621 16621 0 0.00 0 14686 48 0 0
sp_kill_qstorno_ret_qs2qd 1106131 1106131 0 0.00 0 31690 27 0 0
sp_kill_qty_storno 584413 584413 0 0.00 0 37237 58 0 0
sp_lock_selected_doc 21560 21560 0 0.00 0 9089 4 0 0
sp_make_cost_storno 29383 29383 0 0.00 0 32016 55 0 0
sp_multiply_rows_for_pdistr 29383 29383 0 0.00 0 12435 24 0 0
sp_multiply_rows_for_qdistr 21794 21794 0 0.00 0 20989 155 0 0
sp_pay_from_customer 7542 7542 0 0.00 4 19605 113 0 0
sp_pay_to_supplier 6135 6135 0 0.00 4 14470 126 0 0
sp_payment_common 13677 13677 0 0.00 1 12493 32 0 0
sp_qd_handle_on_cancel_clo 1802 1802 0 0.00 0 8538 315 0 0
sp_qd_handle_on_invoice_upd_sts 10645 10645 0 0.00 0 37234 281 0 0
sp_qd_handle_on_reserve_upd_sts 10625 10625 0 0.00 0 12571 49 0 0
sp_reserve_write_off 10625 10625 0 0.00 6 17562 167 0 0
sp_supplier_invoice 5690 5690 0 0.00 5 112180 3368 0 0
sp_supplier_order 5751 5751 0 0.00 4 46852 1269 0 0
srv_aggregate_perf_data 97 96 1 1.03 2722 405989 18823 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 956 134800 42869 0 0
srv_find_qd_qs_mism 1053971 1053971 0 0.00 0 20792 6 0 0
srv_make_invnt_saldo 592 592 0 0.00 39 94558 1157 0 0
srv_make_money_saldo 819 819 0 0.00 1 1555 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 103687 505350 259183 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5087 5087 0 0.00 0 12484 159 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 4671 94 0 0
t$perf-norm:sp_cancel_client_order 1802 1802 0 0.00 0 920 8 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 9422 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 1636 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 1668 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 1993 19 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 26537 1410 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 649 3 0 0
t$perf-norm:sp_client_order 21794 21794 0 0.00 0 13768 18 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 3460 12 0 0
t$perf-norm:sp_pay_from_customer 7542 7542 0 0.00 0 4655 9 0 0
t$perf-norm:sp_pay_to_supplier 6135 6135 0 0.00 0 6258 11 0 0
t$perf-norm:sp_reserve_write_off 10625 10625 0 0.00 0 10310 13 0 0
t$perf-norm:sp_supplier_invoice 5690 5690 0 0.00 0 8250 16 0 0
t$perf-norm:sp_supplier_order 5751 5751 0 0.00 0 12732 16 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 558 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 788 24 0 0
t$perf-norm:srv_make_invnt_saldo 592 592 0 0.00 0 2726 18 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 6107 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 11 5 0 0
v_add_invoice_to_stock 10378 10158 220 2.11 0 5035 18 0 220
v_all_customers 39416 39416 0 0.00 0 70 1 0 0
v_all_suppliers 31652 31652 0 0.00 0 27 1 0 0
v_all_wares 43588 43588 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 2034 8 0 0
v_cancel_client_order 3604 3604 0 0.00 0 4123 5 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 4037 14 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 2988 9 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1093 7 0 0
v_cancel_supplier_invoice 774 744 30 3.87 0 2375 23 0 30
v_cancel_supplier_order 822 822 0 0.00 0 5068 12 0 0
v_cancel_write_off 2018 2018 0 0.00 0 3918 19 0 0
v_max_id_clo_ord 5744 5744 0 0.00 0 709 1 0 0
v_max_id_clo_res 988 988 0 0.00 0 3600 29 0 0
v_max_id_ord_sup 5679 5679 0 0.00 0 4958 5 0 0
v_max_non_paid_invoice 1732 1732 0 0.00 0 2826 5 0 0
v_max_non_paid_realizn 4138 4138 0 0.00 0 3382 5 0 0
v_min_id_clo_ord 5744 5744 0 0.00 0 6489 6 0 0
v_min_id_clo_res 988 988 0 0.00 0 7672 31 0 0
v_min_id_ord_sup 5679 5679 0 0.00 0 6655 9 0 0
v_min_non_paid_invoice 6135 6135 0 0.00 0 8472 25 0 0
v_min_non_paid_realizn 7542 7542 0 0.00 0 12412 22 0 0
v_our_firm 10980 10980 0 0.00 0 28 1 0 0
v_reserve_write_off 21250 21250 0 0.00 0 5041 7 0 0
x_lock_depdocs_on_canc_invoice 11392 11392 0 0.00 0 20723 112 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 9187 325 0 0
x_make_qstorno_1000_1200 5751 5751 0 0.00 2 46842 1184 0 0
x_make_qstorno_1200_2000 5690 5690 0 0.00 2 111923 3227 0 0
x_make_qstorno_2100_3300 469256 469256 0 0.00 0 90426 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-02-2023 19:40:46 to 12-02-2023 19:40:50.

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:44 16 8192000000 8811663984 8827215872 15153216 0.184 5 3 4 0 15339808 79488 107104
17:09:45 25232 8192000000 9897054752 10204581888 975484000 10.640 100 88 176 0 1005085168 5985856 23615312
17:11:13 26017 8192000000 9930112112 10248429568 1009131184 10.967 100 85 168 0 1039208672 8113504 21963984
17:12:42 17543 8192000000 9970676992 10298380288 1049566384 11.357 100 87 172 0 1081431024 9569312 22295328
17:14:01 11341 8192000000 10023949152 10368753664 1107111680 11.905 100 75 151 1 1135244432 10197312 17935440
17:15:22 3551 8192000000 10087428960 10445549568 1171527888 12.511 100 83 165 0 1202562448 12344816 18689744
17:16:30 3775 8192000000 10122817488 10490347520 1211729664 12.885 100 86 166 1 1242881648 12556320 18595664
17:17:37 2868 8192000000 10151631904 10525581312 1238445920 13.132 100 83 165 2 1270177824 13602288 18129616
17:18:43 1779 8192000000 10184321616 10565562368 1271570432 13.436 100 89 176 2 1303432400 12621888 19240080
17:19:47 1362 8192000000 10196584288 10584100864 1288470928 13.590 100 83 166 1 1320441616 13389952 18580736
17:20:50 2786 8192000000 10215549824 10607603712 1302864096 13.721 100 73 146 0 1331646992 13173568 15609328
17:21:54 10587 8192000000 10203108128 10609610752 1306373296 13.753 100 34 68 0 1321263856 7531424 7359136
17:23:05 4974 8192000000 10202908304 10618343424 1320056448 13.877 100 29 59 0 1333046800 6261632 6728720
17:24:13 12152 8192000000 10202772608 10625433600 1323887104 13.912 100 14 26 0 1332079424 5247760 2944560
17:25:26 33476 8192000000 10224857712 10638663680 1330784480 13.974 100 52 105 1 1348342640 4544336 13013824
17:27:01 6446 8192000000 10224560576 10642341888 1332251584 13.987 100 23 46 0 1344272528 6401904 5619040
17:28:12 7816 8192000000 10209108864 10631745536 1305026112 13.741 98 13 26 0 1313242976 5221072 2995792
17:29:22 10525 8192000000 10177647808 10591096832 1260347248 13.333 97 14 27 0 1273008368 10053376 2607744
17:30:35 17177 8192000000 10140955328 10547384320 1198541280 12.763 93 15 30 0 1206562720 4919280 3102160
17:31:53 5314 8192000000 10137540672 10539986944 1238863040 13.136 99 26 52 0 1254581568 9700848 6017680
17:33:01 8987 8192000000 10122163520 10523738112 1213079952 12.898 97 25 48 0 1224163600 5893824 5189824
17:34:16 12622 8192000000 10166861248 10557874176 1257950208 13.311 99 19 36 0 1267452512 4833488 4668816
17:35:30 26641 8192000000 10172625840 10581848064 1282095552 13.532 100 20 40 0 1291766336 5622512 4048272
17:36:57 9223 8192000000 10189804976 10599723008 1298329504 13.680 100 28 56 2 1310932816 6195008 6408304
17:38:11 9409 8192000000 10209135568 10628300800 1321912800 13.894 100 14 27 1 1329781264 4698224 3170240
17:39:25 13649 8192000000 10246288480 10655956992 1341215600 14.068 100 39 74 1 1357119136 7154128 8749408
17:40:42 13609 8192000000 10275105728 10686001152 1363390544 14.268 100 48 96 0 1384027968 9859920 10777504
17:41:58 82273 8192000000 10272039392 10699399168 1375277040 14.374 100 35 67 2 1393573312 9802208 8494064
17:44:23 29558 8192000000 10293648816 10721288192 1382408288 14.438 100 34 69 0 1406676368 16178720 8089360
17:45:59 9305 8192000000 10325723840 10749149184 1397761152 14.575 100 44 83 0 1424637552 17017968 9858432
17:47:15 8554 8192000000 10326806432 10755579904 1405339632 14.643 100 51 99 0 1440911072 24119792 11451648
17:48:27 3121 8192000000 10339804816 10765725696 1410912032 14.692 100 59 114 4 1450046784 25283776 13850976
17:49:36 22961 8192000000 10340160864 10769104896 1410023360 14.684 100 51 102 0 1449205168 27434752 11747056
17:51:06 5759 8192000000 10358152336 10784702464 1425258256 14.819 100 54 108 0 1465924160 27593232 13072672
17:52:15 2495 8192000000 10338727776 10759438336 1401955088 14.612 100 70 136 2 1452716832 33501264 17260480
17:53:24 4122 8192000000 10328006128 10746589184 1375010144 14.372 98 63 124 0 1420065712 30426016 14629552
17:54:33 955 8192000000 10347140848 10754764800 1395725136 14.557 100 86 174 0 1454017952 38016144 20276672
17:55:37 3881 8192000000 10347470448 10770026496 1399375312 14.589 100 47 93 1 1452343584 40675296 12292976
17:56:44 8011 8192000000 10330045680 10753101824 1352032624 14.166 97 48 97 0 1413101504 48283968 12784912
17:58:11 7239 8192000000 10338394512 10759237632 1381311824 14.428 100 47 91 0 1444674992 49690432 13672736
17:59:21 11038 8192000000 10304511776 10731302912 1357131984 14.212 99 27 53 1 1399855040 35259648 7463408
18:00:34 17523 8192000000 10320166544 10753220608 1379127824 14.409 100 33 66 0 1425470624 37641680 8701120
18:02:17 17455 8192000000 10280912048 10719887360 1356575744 14.207 99 20 40 0 1382106768 20364320 5166704
18:03:40 31978 8192000000 10272148224 10714382336 1354167792 14.185 100 20 40 0 1378874496 19278000 5428704
18:05:17 52423 8192000000 10234345872 10671628288 1250948080 13.247 92 13 27 1 1270143408 16295856 2899472
18:07:11 25756 8192000000 10271311024 10693238784 1331553232 13.981 98 37 74 1 1348048576 8385392 8109952
18:08:39 35197 8192000000 10292704208 10730872832 1371369840 14.339 100 25 51 0 1389854272 12672192 5812240
18:10:15 112314 8192000000 10307330048 10765127680 1365818608 14.290 99 9 18 0 1397565376 29519456 2227312
18:13:10 130118 8192000000 10307108224 10745892864 1347148240 14.122 97 30 57 1 1359981040 5891840 6940960
18:16:21 18578 8192000000 10327883408 10763907072 1402164400 14.614 100 50 98 3 1425801920 12999296 10638224
18:17:41 54127 8192000000 10324268752 10776264704 1405916944 14.648 100 23 47 0 1430947232 19338256 5692032
18:19:36 39102 8192000000 10315718192 10784747520 1402151520 14.614 100 20 40 0 1429451280 21554896 5744864
18:21:33 30793 8192000000 10311370224 10763493376 1398350048 14.580 99 20 39 0 1416595968 13418912 4827008
18:23:07 46335 8192000000 10297369376 10759475200 1389244448 14.499 99 15 30 0 1405753072 13013776 3494848
18:24:57 6831 8192000000 10304715744 10762493952 1389606640 14.502 99 28 56 1 1406792448 10567840 6617968
18:26:05 59214 8192000000 10304108912 10765168640 1400908912 14.603 100 11 20 0 1414977088 12100880 1967296
18:28:04 64158 8192000000 10294009664 10756403200 1367019824 14.300 98 17 33 1 1386447808 16084000 3343984
18:30:24 81496 8192000000 10292310896 10759966720 1378425616 14.402 99 15 30 1 1396472704 14824016 3223072
18:32:49 42229 8192000000 10287844304 10754678784 1379006304 14.408 99 11 23 0 1396298752 14558352 2734096
18:34:43 74009 8192000000 10283931952 10743767040 1363190512 14.266 98 2 4 0 1381114384 17508992 414880
18:36:58 43573 8192000000 10289691072 10740441088 1367541792 14.305 98 16 32 0 1386641872 15007312 4092768
18:38:44 50192 8192000000 10293156144 10750599168 1366676224 14.297 99 17 34 0 1390977648 20239216 4062208
18:41:12 84296 8192000000 10286721664 10740629504 1354794688 14.191 98 10 20 0 1373707056 16959888 1952480
18:44:04 41089 8192000000 10289765376 10744995840 1346835888 14.119 97 18 34 0 1359697168 8939072 3922208
18:46:13 47593 8192000000 10294798176 10758975488 1360151088 14.239 98 25 50 0 1381002560 15203536 5647936
18:48:05 14419 8192000000 10312351824 10752749568 1384827600 14.460 100 43 84 0 1406465184 12674192 8963392
18:49:20 134800 8192000000 10254808576 10712924160 1317459632 13.854 96 30 61 0 1337096736 12939360 6697744
18:52:36 43821 8192000000 10243355440 10693115904 1304795984 13.739 96 23 46 1 1318869776 8675488 5398304
18:54:22 52937 8192000000 10250242736 10709012480 1346651280 14.117 99 10 20 0 1358854656 9805104 2398272
18:56:19 27054 8192000000 10259798608 10702598144 1321449200 13.890 97 26 50 1 1338997168 12242576 5305392
18:57:53 77122 8192000000 10256399744 10709606400 1333688912 14.000 99 11 22 0 1349509744 13191136 2629696
19:01:16 54328 8192000000 10254362368 10707775488 1330953952 13.976 98 16 31 1 1347075264 12674240 3447072
19:03:17 77358 8192000000 10269309840 10725494784 1357904656 14.219 99 21 41 0 1373922864 10933200 5085008
19:05:39 56697 8192000000 10282851392 10728280064 1351509152 14.161 98 28 54 0 1365880176 8532768 5838256
19:07:37 31832 8192000000 10301238160 10744766464 1367492304 14.305 99 37 74 0 1389851168 14533824 7825040
19:09:14 17454 8192000000 10312061472 10749566976 1376304208 14.383 99 54 108 0 1401831696 13707120 11820368
19:10:33 56865 8192000000 10293320032 10742976512 1349023312 14.139 97 23 46 0 1363209936 9402112 4784512
19:12:33 61639 8192000000 10300386064 10735079424 1330584560 13.972 96 27 54 0 1345128640 8880016 5664064
19:14:37 41888 8192000000 10283799632 10729664512 1358809552 14.227 99 16 32 0 1374335856 12152288 3374016
19:16:24 99830 8192000000 10273379872 10715336704 1340138912 14.059 98 26 52 0 1357818576 11645088 6034576
19:19:05 37347 8192000000 10274763904 10720854016 1342794304 14.083 98 28 55 0 1362480000 13570672 6115024
19:20:45 60249 8192000000 10255306192 10728628224 1339996480 14.057 98 2 4 0 1357394656 17059312 338864
19:22:45 43288 8192000000 10252779216 10705334272 1332835712 13.993 98 4 8 0 1344672864 11113824 723328
19:24:29 29267 8192000000 10270519472 10724618240 1359935808 14.237 100 29 59 1 1376054912 8170032 7949072
19:25:59 36458 8192000000 10282693776 10729132032 1348760976 14.136 98 27 53 4 1367647392 12461616 6424800
19:27:49 43899 8192000000 10267621312 10722947072 1344487456 14.098 98 10 20 0 1361910768 15058288 2365024
19:29:39 14571 8192000000 10282131472 10713440256 1315818928 13.839 96 47 95 0 1335992352 8855168 11318256
19:30:55 66212 8192000000 10295331440 10763755520 1374166240 14.364 100 24 48 2 1402900096 23160080 5573776
19:33:10 103409 8192000000 10287808976 10745901056 1347624352 14.126 97 11 22 0 1367811200 17564592 2622256
19:35:54 62543 8192000000 10265126544 10722775040 1337091728 14.031 98 23 44 0 1353383104 11659680 4631696
19:37:58 51081 8192000000 9176183824 9430536192 332743824 3.903 24 11 22 0 338708256 3648032 2316400

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-02-2023 19:40:52 to 12-02-2023 19:41:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 110 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-02-2023 19:41:08 to 12-02-2023 19:41:10.

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          245046
MON$OLDEST_ACTIVE               245867
MON$OLDEST_SNAPSHOT             245867
MON$NEXT_TRANSACTION            245867
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                       2026720
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.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 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 ---
:199: 
:200: mscs-test-161.budzdorov.ru    Sun Feb 12 19:38:51 2023
:201:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:202: 
--- end of diff output ---

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

12.02.23 19:41:11. 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-12 19:41:14 No severe PSQL-related problems occured
12.02.23 19:41:15. End of report.