Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.12.2022 01: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 15039592 kB
MemAvailable 30056056 kB
Buffers 0 kB
Cached 16649788 kB
SwapCached 3408 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 2022-12-15 01:38:33
DTS_END                         2022-12-15 01:38:33.9200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_PAY_FROM_CUSTOMER(18:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57130
TRN_ID                          244450
ATT_ID                          675
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: 9142.45 20627 1097094
customer order: creation 176.80 342 21216
customer order: refuse 15.01 351 1801
order to supplier: creation 47.57 1308 5708
order to supplier: removal 3.46 88736 415
invoice (draft): creation 47.14 3500 5657
invoice (draft): removal 51.11 777 6133
invoice accept: apply 43.10 16741 5172
invoice accept: cancel 46.35 813 5562
customer reserve: creation 3885.36 170 466243
customer reserve: removal 4531.89 53 543827
realization accept: apply 86.27 178 10352
realization accept: cancel 76.53 103 9183
payment from customer: creation 60.47 103 7256
payment from customer: removal 5.68 86 681
payment to supplier: creation 50.35 140 6042
payment to supplier: removal 3.25 72 390
service: total inventory turnovers 4.74 1187 569
service: total monetary turnovers 6.60 29 792
service: aggregate perf. data 0.76 24358 91
service: refresh index statistics 0.03 273499 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-12-2022 01:39:20 to 15-12-2022 01:39:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5546
TEST_TIME 2 4134
TEST_TIME 3 4901
TEST_TIME 4 4607
TEST_TIME 5 4728
TEST_TIME 6 4726
TEST_TIME 7 5160
TEST_TIME 8 5058
TEST_TIME 9 5091
TEST_TIME 10 5377
TEST_TIME 11 5640
TEST_TIME 12 5647
TEST_TIME 13 5762
TEST_TIME 14 5964
TEST_TIME 15 6458
TEST_TIME 16 7303
TEST_TIME 17 7928
TEST_TIME 18 8101
TEST_TIME 19 8142
TEST_TIME 20 8120
TEST_TIME 21 8858
TEST_TIME 22 9100
TEST_TIME 23 9269
TEST_TIME 24 9243
TEST_TIME 25 9194
TEST_TIME 26 9275
TEST_TIME 27 9725
TEST_TIME 28 10048
TEST_TIME 29 10364
TEST_TIME 30 10260
TEST_TIME 31 10121
TEST_TIME 32 9839
TEST_TIME 33 9743
TEST_TIME 34 9629
TEST_TIME 35 9564
TEST_TIME 36 9592
TEST_TIME 37 9522
TEST_TIME 38 9426
TEST_TIME 39 9323
TEST_TIME 40 9286
TEST_TIME 41 9170
TEST_TIME 42 9066
TEST_TIME 43 9202
TEST_TIME 44 9239
TEST_TIME 45 9162
TEST_TIME 46 9030
TEST_TIME 47 9032
TEST_TIME 48 9001
TEST_TIME 49 8936
TEST_TIME 50 8950
TEST_TIME 51 8880
TEST_TIME 52 8794
TEST_TIME 53 8687
TEST_TIME 54 8660
TEST_TIME 55 8597
TEST_TIME 56 8674
TEST_TIME 57 8661
TEST_TIME 58 8650
TEST_TIME 59 8650
TEST_TIME 60 8716
TEST_TIME 61 8894
TEST_TIME 62 8830
TEST_TIME 63 8854
TEST_TIME 64 9026
TEST_TIME 65 8937
TEST_TIME 66 8990
TEST_TIME 67 9115
TEST_TIME 68 9073
TEST_TIME 69 9212
TEST_TIME 70 9232
TEST_TIME 71 9253
TEST_TIME 72 9214
TEST_TIME 73 9108
TEST_TIME 74 9042
TEST_TIME 75 9028
TEST_TIME 76 9062
TEST_TIME 77 8994
TEST_TIME 78 8950
TEST_TIME 79 8928
TEST_TIME 80 8877
TEST_TIME 81 8872
TEST_TIME 82 8849
TEST_TIME 83 8977
TEST_TIME 84 8987
TEST_TIME 85 9000
TEST_TIME 86 9047
TEST_TIME 87 9059
TEST_TIME 88 9069
TEST_TIME 89 9082
TEST_TIME 90 9153
TEST_TIME 91 9126
TEST_TIME 92 9150
TEST_TIME 93 9184
TEST_TIME 94 9147
TEST_TIME 95 9294
TEST_TIME 96 9203
TEST_TIME 97 9162
TEST_TIME 98 9147
TEST_TIME 99 9112
TEST_TIME 100 9088
TEST_TIME 101 9064
TEST_TIME 102 9033
TEST_TIME 103 8976
TEST_TIME 104 8960
TEST_TIME 105 8987
TEST_TIME 106 8940
TEST_TIME 107 8954
TEST_TIME 108 8967
TEST_TIME 109 8963
TEST_TIME 110 8935
TEST_TIME 111 8991
TEST_TIME 112 8994
TEST_TIME 113 8975
TEST_TIME 114 8993
TEST_TIME 115 8994
TEST_TIME 116 9047
TEST_TIME 117 9069
TEST_TIME 118 9126
TEST_TIME 119 9133
TEST_TIME 120 9144

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-12-2022 01:39:24 to 15-12-2022 01:39:27.

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 1529691 1529691 0 0.00 0 25732 6 0 0
doc_list_biud 1529691 1529691 0 0.00 0 38817 25 0 0
sp_add_invoice_to_stock 5247 5172 75 1.42 50 181119 16741 0 75
sp_cancel_adding_invoice 5562 5562 0 0.00 4 58245 813 0 0
sp_cancel_client_order 1801 1801 0 0.00 4 10944 352 0 0
sp_cancel_customer_reserve 543827 543827 0 0.00 0 38820 53 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 7795 86 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 3 3262 72 0 0
sp_cancel_supplier_invoice 6138 6133 5 0.08 1 14478 777 0 5
sp_cancel_supplier_order 415 415 0 0.00 8 937242 88736 0 0
sp_cancel_write_off 9183 9183 0 0.00 1 11197 103 0 0
sp_client_order 21216 21216 0 0.00 3 25090 343 0 0
sp_customer_reserve 466243 466243 0 0.00 0 49609 170 0 0
sp_fill_shopping_cart 32577 32577 0 0.00 1 23720 48 0 0
sp_fill_shopping_cart_clo_res 466243 466243 0 0.00 0 22893 15 0 0
sp_get_clo_for_invoice 5171 5171 0 0.00 0 34225 306 0 0
sp_kill_cost_storno 15816 15816 0 0.00 0 11132 50 0 0
sp_kill_qstorno_ret_qs2qd 1103385 1103385 0 0.00 0 38654 29 0 0
sp_kill_qty_storno 582444 582444 0 0.00 0 38816 64 0 0
sp_lock_selected_doc 21391 21391 0 0.00 0 6032 4 0 0
sp_make_cost_storno 28821 28821 0 0.00 0 24387 51 0 0
sp_multiply_rows_for_pdistr 28821 28821 0 0.00 0 14116 33 0 0
sp_multiply_rows_for_qdistr 21216 21216 0 0.00 0 19721 147 0 0
sp_pay_from_customer 7256 7256 0 0.00 4 14285 104 0 0
sp_pay_to_supplier 6042 6042 0 0.00 3 15007 140 0 0
sp_payment_common 13298 13298 0 0.00 1 10012 28 0 0
sp_qd_handle_on_cancel_clo 1801 1801 0 0.00 0 10939 322 0 0
sp_qd_handle_on_invoice_upd_sts 10733 10733 0 0.00 0 30392 324 0 0
sp_qd_handle_on_reserve_upd_sts 10352 10352 0 0.00 0 20256 63 0 0
sp_reserve_write_off 10352 10352 0 0.00 6 25760 178 0 0
sp_supplier_invoice 5657 5657 0 0.00 6 94003 3501 0 0
sp_supplier_order 5708 5708 0 0.00 5 46881 1308 0 0
srv_aggregate_perf_data 91 91 0 0.00 3092 265806 24359 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1511 119727 43971 0 0
srv_find_qd_qs_mism 1048885 1048885 0 0.00 0 20308 5 0 0
srv_make_invnt_saldo 569 569 0 0.00 34 113738 1188 0 0
srv_make_money_saldo 792 792 0 0.00 0 1520 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 108055 421003 273500 0 0
t$perf-abend:sp_add_invoice_to_stock 75 75 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5172 5172 0 0.00 0 17534 200 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 0 5710 134 0 0
t$perf-norm:sp_cancel_client_order 1801 1801 0 0.00 0 9348 17 0 0
t$perf-norm:sp_cancel_customer_reserve 736 736 0 0.00 0 680 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 2215 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 32 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 10544 38 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 31801 1317 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 2929 6 0 0
t$perf-norm:sp_client_order 21216 21216 0 0.00 0 12787 20 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 2040 10 0 0
t$perf-norm:sp_pay_from_customer 7256 7256 0 0.00 0 13965 13 0 0
t$perf-norm:sp_pay_to_supplier 6042 6042 0 0.00 0 9614 16 0 0
t$perf-norm:sp_reserve_write_off 10352 10352 0 0.00 0 8901 12 0 0
t$perf-norm:sp_supplier_invoice 5657 5657 0 0.00 0 9150 18 0 0
t$perf-norm:sp_supplier_order 5708 5708 0 0.00 0 15694 22 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 282 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 9358 145 0 0
t$perf-norm:srv_make_invnt_saldo 569 569 0 0.00 0 1088 22 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 7333 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10490 10340 150 1.42 0 9150 19 0 150
v_all_customers 38128 38128 0 0.00 0 35 1 0 0
v_all_suppliers 31178 31178 0 0.00 0 25 1 0 0
v_all_wares 42428 42428 0 0.00 0 38 1 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 2998 11 0 0
v_cancel_client_order 3602 3602 0 0.00 0 5682 6 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 4237 8 0 0
v_cancel_customer_reserve 1472 1472 0 0.00 0 3008 8 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 2808 10 0 0
v_cancel_supplier_invoice 802 792 10 1.24 0 1041 16 0 10
v_cancel_supplier_order 830 830 0 0.00 0 3365 8 0 0
v_cancel_write_off 2010 2010 0 0.00 0 5610 23 0 0
v_max_id_clo_ord 5707 5707 0 0.00 0 555 1 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 3519 16 0 0
v_max_id_ord_sup 5655 5655 0 0.00 0 7013 11 0 0
v_max_non_paid_invoice 1815 1815 0 0.00 0 422 2 0 0
v_max_non_paid_realizn 4031 4031 0 0.00 0 6736 6 0 0
v_min_id_clo_ord 5707 5707 0 0.00 0 2932 8 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 8277 21 0 0
v_min_id_ord_sup 5655 5655 0 0.00 0 5715 14 0 0
v_min_non_paid_invoice 6042 6042 0 0.00 0 7785 21 0 0
v_min_non_paid_realizn 7256 7256 0 0.00 0 10008 21 0 0
v_our_firm 10750 10750 0 0.00 0 23 1 0 0
v_reserve_write_off 20702 20702 0 0.00 0 2806 7 0 0
x_lock_depdocs_on_canc_invoice 11299 11299 0 0.00 0 22351 127 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 6926 351 0 0
x_make_qstorno_1000_1200 5708 5708 0 0.00 1 46874 1217 0 0
x_make_qstorno_1200_2000 5657 5657 0 0.00 2 93977 3321 0 0
x_make_qstorno_2100_3300 466243 466243 0 0.00 0 49608 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-12-2022 01:39:28 to 15-12-2022 01:39:32.

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
23:08:37 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:38 20084 8192000000 9905622688 10215890944 981596320 10.700 100 92 184 0 1012313104 5789776 24927008
23:11:03 16399 8192000000 9941040496 10262638592 1024082672 11.111 100 88 176 0 1054776368 7831712 22861984
23:12:34 12667 8192000000 9976272304 10310176768 1061879824 11.474 100 84 168 0 1092558288 9721152 20957312
23:13:51 14705 8192000000 10030306304 10377302016 1117175120 12.000 100 80 160 0 1146820192 9881808 19763264
23:15:10 4617 8192000000 10088609696 10445914112 1176328112 12.556 100 79 158 0 1207332640 12828304 18176224
23:16:17 5505 8192000000 10125067040 10491707392 1212402704 12.891 100 84 167 0 1244054000 13023392 18627904
23:17:25 1796 8192000000 10169208560 10542821376 1255749248 13.291 100 86 169 0 1287993040 13507296 18736496
23:18:30 1574 8192000000 10197500736 10579603456 1288781856 13.593 100 89 178 0 1322966880 13738944 20446080
23:19:33 2019 8192000000 10219460352 10612121600 1311210864 13.797 100 71 143 0 1337297024 10838896 15247264
23:20:36 36983 8192000000 10206214528 10615537664 1317383904 13.853 100 27 54 1 1329510736 6094704 6032128
23:22:14 5740 8192000000 10216419984 10627481600 1323845952 13.912 100 32 63 0 1337722000 7256560 6619488
23:23:23 6673 8192000000 10217009440 10632232960 1329702608 13.964 100 22 45 1 1339553808 4714672 5136528
23:24:31 17380 8192000000 10223661024 10646654976 1337570768 14.036 100 14 28 0 1346340416 5884976 2884672
23:25:49 50610 8192000000 10242633056 10662817792 1345037008 14.103 100 40 80 0 1360535440 6428080 9070352
23:27:43 4791 8192000000 10245826736 10666119168 1349233072 14.141 100 29 58 1 1361285808 4928160 7124576
23:28:51 6754 8192000000 10209088688 10629996544 1309004480 13.777 99 18 36 1 1318505600 5503776 3997344
23:29:58 8496 8192000000 10144219728 10548310016 1207672448 12.848 94 20 40 0 1217498016 5294496 4531072
23:31:08 14591 8192000000 10145546416 10553716736 1198932400 12.766 94 23 46 0 1209599840 5857296 4810144
23:32:24 6134 8192000000 10123236384 10522038272 1210803824 12.877 97 26 50 1 1222297824 5963008 5530992
23:33:31 13106 8192000000 10147510896 10547929088 1232130976 13.074 98 23 45 0 1242834384 5154480 5548928
23:34:45 59188 8192000000 10181326528 10584510464 1283153232 13.542 100 32 64 2 1296077936 5861504 7063200
23:36:45 6733 8192000000 10183721568 10595487744 1293255648 13.634 100 21 40 0 1302703344 4739344 4708352
23:37:58 9793 8192000000 10202641648 10617499648 1312048320 13.805 100 25 51 0 1322953424 5448016 5457088
23:39:15 33864 8192000000 10236037936 10647384064 1336942384 14.030 100 42 85 1 1353669680 6790416 9936880
23:41:03 36633 8192000000 10255031216 10679689216 1351872672 14.164 100 38 76 1 1370414192 10854880 7686640
23:42:47 14105 8192000000 10270656736 10694754304 1368086304 14.310 100 23 44 0 1386624656 12855296 5683056
23:44:10 7435 8192000000 10300557232 10714808320 1379570368 14.413 100 58 116 0 1408502720 15945744 12986608
23:45:20 8020 8192000000 10303537360 10725257216 1388703296 14.494 100 46 91 0 1417642496 18785648 10153552
23:46:46 11904 8192000000 10324144352 10745073664 1399289504 14.589 100 49 97 2 1435268528 23627392 12351632
23:48:02 15587 8192000000 10330401392 10760732672 1406868096 14.656 100 38 73 1 1443240608 27328272 9044240
23:49:21 8243 8192000000 10341471744 10768900096 1416795104 14.744 100 39 78 0 1450378096 24175408 9407584
23:50:39 7756 8192000000 10349571552 10767372288 1413693136 14.717 100 71 139 0 1458558864 28945472 15920256
23:51:50 6330 8192000000 10352553968 10770075648 1395117120 14.551 99 74 146 3 1448652240 35251216 18283904
23:53:11 2585 8192000000 10348636928 10764193792 1400830496 14.602 100 65 129 2 1456497584 39942176 15724912
23:54:19 8615 8192000000 10319071680 10730708992 1367492496 14.305 100 63 123 1 1426934576 43613728 15828352
23:55:41 3987 8192000000 10304739728 10715979776 1357648368 14.216 100 56 112 1 1407417984 35574608 14195008
23:56:48 6339 8192000000 10263462656 10674921472 1322060224 13.895 98 42 80 1 1357154384 25043760 10050400
23:57:58 27298 8192000000 10268673008 10683351040 1293263872 13.634 96 36 68 0 1338823664 35766864 9792928
23:59:33 13984 8192000000 10261837584 10696347648 1334477424 14.008 100 27 54 0 1381519600 39286240 7755936
00:00:53 4190 8192000000 10308013840 10722906112 1361728464 14.253 100 63 123 2 1410299824 32819664 15751696
00:01:59 27383 8192000000 10295085424 10723540992 1356423728 14.205 99 27 54 0 1389865776 26607936 6834112
00:03:32 1506 8192000000 10308263632 10720661504 1362502720 14.260 100 51 102 1 1407008160 31771408 12734032
00:04:35 17569 8192000000 10298503488 10726883328 1351205376 14.158 99 29 56 0 1396455328 36830432 8419520
00:06:16 39955 8192000000 10280444176 10709651456 1337890640 14.038 98 36 68 1 1371402944 24057056 9455248
00:08:05 26317 8192000000 10285809728 10720780288 1368485184 14.313 100 21 42 2 1381626464 8818512 4322768
00:09:31 74001 8192000000 10289096016 10739490816 1385396448 14.465 100 23 47 1 1398309248 7471648 5441152
00:11:50 25313 8192000000 10304919824 10745569280 1392761232 14.530 100 40 79 1 1409255552 8434688 8059632
00:13:20 52650 8192000000 10286853040 10732486656 1367804928 14.307 99 12 23 0 1382331200 12450160 2076112
00:15:15 55612 8192000000 10298528608 10748153856 1381306000 14.428 99 27 54 0 1401727600 13769376 6652224
00:17:40 78790 8192000000 10301988000 10754166784 1391774048 14.522 99 15 30 0 1406427648 11093872 3559728
00:20:07 37164 8192000000 10313405568 10772832256 1408426912 14.670 100 9 18 0 1428340448 17436736 2476800
00:21:45 112737 8192000000 10300130352 10770456576 1387330896 14.482 99 34 67 0 1414070816 17803440 8936480
00:24:40 2594 8192000000 10308262256 10756841472 1404150688 14.632 100 37 72 3 1418389584 6163600 8075296
00:25:49 55187 8192000000 10298041040 10767192064 1393841376 14.540 99 14 28 1 1409522864 12423696 3257792
00:27:53 56531 8192000000 10299011072 10767761408 1395252640 14.553 99 10 20 1 1410204032 12617248 2334144
00:30:01 85610 8192000000 10287744336 10754560000 1389361856 14.500 99 15 30 0 1399666192 6649504 3654832
00:32:31 24759 8192000000 10298639296 10761060352 1401251888 14.606 100 25 50 0 1416910304 9561024 6097392
00:33:57 68572 8192000000 10289267968 10771427328 1400895040 14.603 100 8 16 0 1413472336 10717024 1860272
00:36:09 60527 8192000000 10300812640 10759307264 1338151184 14.041 95 25 50 0 1356623024 12560848 5910992
00:38:10 79055 8192000000 10274489568 10725662720 1290035952 13.605 93 21 40 3 1314792480 19743072 5013456
00:40:30 45410 8192000000 10265466496 10715824128 1338752112 14.046 97 30 60 0 1361618752 16258288 6608352
00:42:25 48399 8192000000 10274897584 10724728832 1342184304 14.077 98 18 36 0 1358156784 11982928 3989552
00:44:17 31310 8192000000 10296837072 10743554048 1372637744 14.351 100 31 62 0 1396857408 17322016 6897648
00:45:55 35449 8192000000 10286764208 10718908416 1316508208 13.845 96 41 82 1 1334644976 8961168 9175600
00:47:30 59779 8192000000 10269347968 10730627072 1322818176 13.902 97 15 30 1 1346489584 20358096 3313312
00:49:31 119727 8192000000 10259384864 10706378752 1296973936 13.668 95 18 36 0 1319706368 18920544 3811888
00:52:41 73708 8192000000 10257113392 10715779072 1354286736 14.186 99 33 67 1 1371392784 9834624 7271424
00:55:58 91682 8192000000 10251415856 10707140608 1326874704 13.939 97 12 24 0 1341209200 11368480 2966016
00:58:32 49192 8192000000 10263264944 10717519872 1326130384 13.932 97 21 42 0 1345798928 14744384 4924160
01:00:22 64725 8192000000 10267325760 10737254400 1367546848 14.305 100 16 32 0 1389548656 18074800 3927008
01:02:27 34830 8192000000 10275483856 10730582016 1354622688 14.189 98 18 36 1 1369149312 10483440 4043184
01:04:21 46924 8192000000 10270405408 10728198144 1331631856 13.982 97 13 25 0 1347717616 13612944 2472816
01:06:08 37621 8192000000 10270561296 10723840000 1352846944 14.173 99 21 42 0 1367596256 10171184 4578128
01:07:48 86283 8192000000 10302371520 10743619584 1382743184 14.441 100 11 22 0 1400918144 15912544 2262416
01:10:16 62979 8192000000 10313196416 10760454144 1384587456 14.458 99 20 40 0 1398854304 10187488 4079360
01:12:21 58796 8192000000 10309059888 10779828224 1387994896 14.488 99 13 26 0 1411835232 20980176 2860160
01:14:22 103891 8192000000 10284742704 10740244480 1366455296 14.295 98 25 50 0 1386826544 14512768 5858480
01:17:06 55740 8192000000 10291602784 10755653632 1395938736 14.559 100 41 82 0 1414193360 9119856 9134768
01:19:04 39080 8192000000 10304035824 10763444224 1406930544 14.657 100 21 43 0 1417585520 6521264 4133712
01:20:46 55569 8192000000 10298607424 10759241728 1390360240 14.509 99 18 37 0 1403335936 8304016 4671680
01:23:21 86362 8192000000 10266000736 10724040704 1321070976 13.886 95 26 52 0 1340934832 13218384 6645472
01:25:54 55320 8192000000 10267016400 10736201728 1360669952 14.243 99 10 20 2 1375324128 12362640 2291536
01:27:54 34189 8192000000 10288064864 10731532288 1353040400 14.175 98 33 67 0 1367901056 7749856 7110800
01:29:34 48102 8192000000 10262711040 10723631104 1352387312 14.169 99 4 8 0 1365032400 11789136 855952
01:31:25 24458 8192000000 10274049536 10717192192 1343043808 14.085 98 35 70 1 1359837200 8965680 7827712
01:32:53 40410 8192000000 10294982256 10739638272 1357907872 14.219 98 37 74 0 1378150944 11973216 8269856
01:34:34 67194 8192000000 10279208432 10726502400 1284897280 13.558 93 19 38 0 1304709568 15525328 4286960
01:36:41 102177 8192000000 10261306400 10716430336 1276253856 13.479 93 7 14 0 1288955296 11415104 1286336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-12-2022 01:39:34 to 15-12-2022 01:39:50.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-12-2022 01:39:51 to 15-12-2022 01:39:53.

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          243625
MON$OLDEST_ACTIVE               244566
MON$OLDEST_SNAPSHOT             244566
MON$NEXT_TRANSACTION            244566
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                       2027232
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Thu Dec 15 01:38:42 2022
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Thu Dec 15 01:38:45 2022
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Thu Dec 15 01:38:46 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Thu Dec 15 01:38:46 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Thu Dec 15 01:38:46 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Thu Dec 15 01:38:46 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Thu Dec 15 01:38:46 2022
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Thu Dec 15 01:38:46 2022
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
--- end of diff output ---

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

15.12.22 01:39:54. 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
2022-12-15 01:39:56 No severe PSQL-related problems occured
15.12.22 01:39:58. End of report.