Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.04.2022 01: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 16776756 kB
MemAvailable 31564092 kB
Buffers 0 kB
Cached 14958132 kB
SwapCached 2636 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.10
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-04-22 01:38:18
DTS_END                         2022-04-22 01:38:18.2760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52274
TRN_ID                          243675
ATT_ID                          692
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: 9070.55 21718 1088466
customer order: creation 178.32 351 21398
customer order: refuse 15.27 392 1832
order to supplier: creation 46.82 1318 5618
order to supplier: removal 3.38 101856 405
invoice (draft): creation 46.51 3495 5581
invoice (draft): removal 50.37 782 6044
invoice accept: apply 42.42 18572 5090
invoice accept: cancel 45.61 848 5473
customer reserve: creation 3891.74 185 467009
customer reserve: removal 4456.89 61 534827
realization accept: apply 86.12 187 10334
realization accept: cancel 76.28 121 9154
payment from customer: creation 59.58 129 7150
payment from customer: removal 5.63 90 676
payment to supplier: creation 50.33 141 6039
payment to supplier: removal 3.39 99 407
service: total inventory turnovers 4.79 1222 575
service: total monetary turnovers 6.28 25 753
service: aggregate perf. data 0.81 16626 97
service: refresh index statistics 0.03 287855 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-04-2022 01:39:45 to 22-04-2022 01:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2242
TEST_TIME 2 3215
TEST_TIME 3 4508
TEST_TIME 4 4216
TEST_TIME 5 4695
TEST_TIME 6 4214
TEST_TIME 7 4320
TEST_TIME 8 4345
TEST_TIME 9 4438
TEST_TIME 10 4446
TEST_TIME 11 4735
TEST_TIME 12 5086
TEST_TIME 13 5684
TEST_TIME 14 6018
TEST_TIME 15 6831
TEST_TIME 16 6936
TEST_TIME 17 7491
TEST_TIME 18 7814
TEST_TIME 19 8055
TEST_TIME 20 8304
TEST_TIME 21 8367
TEST_TIME 22 8892
TEST_TIME 23 9311
TEST_TIME 24 9509
TEST_TIME 25 9668
TEST_TIME 26 10161
TEST_TIME 27 10222
TEST_TIME 28 10231
TEST_TIME 29 10331
TEST_TIME 30 10154
TEST_TIME 31 9990
TEST_TIME 32 10027
TEST_TIME 33 10003
TEST_TIME 34 10015
TEST_TIME 35 9865
TEST_TIME 36 9761
TEST_TIME 37 9550
TEST_TIME 38 9490
TEST_TIME 39 9481
TEST_TIME 40 9384
TEST_TIME 41 9280
TEST_TIME 42 9191
TEST_TIME 43 9094
TEST_TIME 44 9118
TEST_TIME 45 9143
TEST_TIME 46 9218
TEST_TIME 47 9188
TEST_TIME 48 9288
TEST_TIME 49 9420
TEST_TIME 50 9396
TEST_TIME 51 9388
TEST_TIME 52 9319
TEST_TIME 53 9531
TEST_TIME 54 9513
TEST_TIME 55 9492
TEST_TIME 56 9537
TEST_TIME 57 9504
TEST_TIME 58 9627
TEST_TIME 59 9713
TEST_TIME 60 9646
TEST_TIME 61 9561
TEST_TIME 62 9470
TEST_TIME 63 9465
TEST_TIME 64 9396
TEST_TIME 65 9389
TEST_TIME 66 9317
TEST_TIME 67 9259
TEST_TIME 68 9185
TEST_TIME 69 9173
TEST_TIME 70 9087
TEST_TIME 71 9186
TEST_TIME 72 9192
TEST_TIME 73 9175
TEST_TIME 74 9254
TEST_TIME 75 9288
TEST_TIME 76 9288
TEST_TIME 77 9269
TEST_TIME 78 9232
TEST_TIME 79 9328
TEST_TIME 80 9341
TEST_TIME 81 9395
TEST_TIME 82 9394
TEST_TIME 83 9457
TEST_TIME 84 9453
TEST_TIME 85 9382
TEST_TIME 86 9333
TEST_TIME 87 9288
TEST_TIME 88 9262
TEST_TIME 89 9236
TEST_TIME 90 9197
TEST_TIME 91 9204
TEST_TIME 92 9193
TEST_TIME 93 9162
TEST_TIME 94 9147
TEST_TIME 95 9189
TEST_TIME 96 9194
TEST_TIME 97 9249
TEST_TIME 98 9192
TEST_TIME 99 9229
TEST_TIME 100 9245
TEST_TIME 101 9261
TEST_TIME 102 9286
TEST_TIME 103 9308
TEST_TIME 104 9275
TEST_TIME 105 9298
TEST_TIME 106 9275
TEST_TIME 107 9341
TEST_TIME 108 9284
TEST_TIME 109 9237
TEST_TIME 110 9273
TEST_TIME 111 9228
TEST_TIME 112 9218
TEST_TIME 113 9163
TEST_TIME 114 9137
TEST_TIME 115 9097
TEST_TIME 116 9106
TEST_TIME 117 9134
TEST_TIME 118 9145
TEST_TIME 119 9135
TEST_TIME 120 9074

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-04-2022 01:39:48 to 22-04-2022 01:39:52.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1520820 1520820 0 0.00 0 38259 6 0 0
doc_list_biud 1520819 1520819 0 0.00 0 67519 27 0 0
sp_add_invoice_to_stock 5164 5090 74 1.43 15 215554 18573 0 74
sp_cancel_adding_invoice 5473 5473 0 0.00 4 67326 849 0 0
sp_cancel_client_order 1832 1832 0 0.00 3 17184 393 0 0
sp_cancel_customer_reserve 534827 534827 0 0.00 1 47614 61 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 2 4190 91 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 6363 99 0 0
sp_cancel_supplier_invoice 6048 6044 4 0.06 2 67526 783 0 4
sp_cancel_supplier_order 405 405 0 0.00 7 1173508 101856 0 0
sp_cancel_write_off 9154 9154 0 0.00 1 25573 121 0 0
sp_client_order 21398 21398 0 0.00 3 24206 351 0 0
sp_customer_reserve 467009 467009 0 0.00 0 52895 186 0 0
sp_fill_shopping_cart 32579 32579 0 0.00 1 11480 42 0 0
sp_fill_shopping_cart_clo_res 466999 466999 0 0.00 0 28290 16 0 0
sp_get_clo_for_invoice 5080 5080 0 0.00 0 27373 326 0 0
sp_kill_cost_storno 15710 15710 0 0.00 0 7280 60 0 0
sp_kill_qstorno_ret_qs2qd 1085257 1085257 0 0.00 0 67517 33 0 0
sp_kill_qty_storno 573149 573149 0 0.00 0 67518 71 0 0
sp_lock_selected_doc 21336 21336 0 0.00 0 6773 6 0 0
sp_make_cost_storno 28603 28603 0 0.00 0 38235 61 0 0
sp_multiply_rows_for_pdistr 28603 28603 0 0.00 0 7936 30 0 0
sp_multiply_rows_for_qdistr 21398 21398 0 0.00 0 24199 166 0 0
sp_pay_from_customer 7150 7150 0 0.00 4 9576 129 0 0
sp_pay_to_supplier 6039 6039 0 0.00 5 14688 141 0 0
sp_payment_common 13189 13189 0 0.00 2 8905 30 0 0
sp_qd_handle_on_cancel_clo 1832 1832 0 0.00 0 17171 351 0 0
sp_qd_handle_on_invoice_upd_sts 10553 10553 0 0.00 0 15227 329 0 0
sp_qd_handle_on_reserve_upd_sts 10334 10334 0 0.00 0 14355 53 0 0
sp_reserve_write_off 10334 10334 0 0.00 6 14759 187 0 0
sp_supplier_invoice 5581 5581 0 0.00 6 106591 3495 0 0
sp_supplier_order 5618 5618 0 0.00 5 80264 1319 0 0
srv_aggregate_perf_data 97 97 0 0.00 2949 128994 16627 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2531 126693 43206 0 0
srv_find_qd_qs_mism 1039859 1039859 0 0.00 0 10225 5 0 0
srv_make_invnt_saldo 575 575 0 0.00 35 153987 1223 0 0
srv_make_money_saldo 753 753 0 0.00 1 683 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 180664 350910 287855 0 0
t$perf-abend:sp_add_invoice_to_stock 74 74 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5090 5090 0 0.00 0 25497 180 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 1 5163 102 0 0
t$perf-norm:sp_cancel_client_order 1832 1832 0 0.00 0 6889 15 0 0
t$perf-norm:sp_cancel_customer_reserve 748 748 0 0.00 0 1015 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 4759 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 5719 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 2300 28 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 28171 1606 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 5064 12 0 0
t$perf-norm:sp_client_order 21398 21398 0 0.00 0 7896 17 0 0
t$perf-norm:sp_customer_reserve 1012 1012 0 0.00 0 7231 13 0 0
t$perf-norm:sp_pay_from_customer 7150 7150 0 0.00 0 4431 12 0 0
t$perf-norm:sp_pay_to_supplier 6039 6039 0 0.00 0 6869 10 0 0
t$perf-norm:sp_reserve_write_off 10334 10334 0 0.00 0 7679 12 0 0
t$perf-norm:sp_supplier_invoice 5581 5581 0 0.00 0 8302 22 0 0
t$perf-norm:sp_supplier_order 5618 5618 0 0.00 0 5896 18 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 475 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 35 1 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 1500 28 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 1310 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 4 0 0
v_add_invoice_to_stock 10298 10150 148 1.43 0 6925 26 0 148
v_all_customers 38758 38758 0 0.00 0 21 1 0 0
v_all_suppliers 30742 30742 0 0.00 0 19 1 0 0
v_all_wares 42792 42792 0 0.00 0 34 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 2479 8 0 0
v_cancel_client_order 3664 3664 0 0.00 0 4286 11 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 2476 14 0 0
v_cancel_customer_reserve 1496 1496 0 0.00 0 7606 14 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 6109 17 0 0
v_cancel_supplier_invoice 834 826 8 0.95 0 5870 27 0 8
v_cancel_supplier_order 810 810 0 0.00 0 2432 15 0 0
v_cancel_write_off 2112 2112 0 0.00 0 6254 21 0 0
v_max_id_clo_ord 5604 5604 0 0.00 0 318 1 0 0
v_max_id_clo_res 1012 1012 0 0.00 0 1567 12 0 0
v_max_id_ord_sup 5577 5577 0 0.00 0 4179 3 0 0
v_max_non_paid_invoice 1849 1849 0 0.00 0 1088 3 0 0
v_max_non_paid_realizn 3821 3821 0 0.00 0 1828 5 0 0
v_min_id_clo_ord 5604 5604 0 0.00 0 4249 6 0 0
v_min_id_clo_res 1012 1012 0 0.00 0 2541 5 0 0
v_min_id_ord_sup 5577 5577 0 0.00 0 4623 8 0 0
v_min_non_paid_invoice 6039 6039 0 0.00 0 8899 19 0 0
v_min_non_paid_realizn 7150 7150 0 0.00 0 7422 25 0 0
v_our_firm 10692 10692 0 0.00 0 19 1 0 0
v_reserve_write_off 20666 20666 0 0.00 0 6971 11 0 0
x_lock_depdocs_on_canc_invoice 11104 11104 0 0.00 0 17354 127 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 8520 386 0 0
x_make_qstorno_1000_1200 5618 5618 0 0.00 1 80187 1236 0 0
x_make_qstorno_1200_2000 5581 5581 0 0.00 3 106567 3341 0 0
x_make_qstorno_2100_3300 467009 467009 0 0.00 0 40884 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-04-2022 01:39:53 to 22-04-2022 01:39:57.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:22 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:23 39870 8192000000 9906513872 10217648128 982017680 10.704 100 83 166 0 1010429264 6466560 21945024
23:11:05 22080 8192000000 9938528288 10260529152 1020768736 11.079 100 86 172 0 1051459728 8757872 21933120
23:12:37 15885 8192000000 9988842992 10324033536 1071454704 11.566 100 83 164 2 1101780592 10392448 19933440
23:13:58 9368 8192000000 10047814144 10398044160 1132176864 12.142 100 78 156 2 1162202720 10966624 19059232
23:15:13 7696 8192000000 10091677808 10454552576 1174774176 12.541 100 78 156 1 1204916624 12120480 18021968
23:16:29 5941 8192000000 10136039968 10508861440 1226438032 13.021 100 85 169 2 1258579632 12878320 19263280
23:17:38 4237 8192000000 10164706080 10541350912 1256767824 13.300 100 87 174 2 1290543136 14827488 18947824
23:18:43 2019 8192000000 10203055840 10587090944 1289781152 13.602 100 85 170 2 1323403984 14707104 18915728
23:19:47 2004 8192000000 10207130992 10600005632 1298231600 13.679 100 71 141 3 1326215296 12163664 15820032
23:20:49 9995 8192000000 10199241552 10605477888 1307858496 13.767 100 31 61 1 1322006544 7733296 6414752
23:22:01 7462 8192000000 10200172880 10614452224 1310331184 13.789 100 26 51 1 1323233488 6717328 6184976
23:23:11 6422 8192000000 10203469632 10621902848 1316729408 13.847 100 37 73 2 1332505072 7362848 8412816
23:24:18 6255 8192000000 10209607344 10627440640 1320962928 13.885 100 15 30 0 1330424288 5940512 3520848
23:25:25 68099 8192000000 10218221280 10640343040 1327915824 13.948 100 25 50 0 1339290128 5240672 6133632
23:27:33 4531 8192000000 10241740640 10650177536 1328341504 13.952 100 57 113 1 1348689472 7155936 13192032
23:28:39 6032 8192000000 10185737552 10597175296 1285737120 13.565 98 15 29 3 1294406800 5109024 3560656
23:29:47 13548 8192000000 10143877952 10547204096 1218361680 12.947 96 24 45 1 1228686432 5063504 5261248
23:31:01 7280 8192000000 10129956576 10532343808 1194493648 12.725 95 22 45 1 1205791648 5996416 5301584
23:32:14 5196 8192000000 10162470512 10565132288 1260036448 13.330 99 22 44 1 1270533424 5313664 5183312
23:33:22 8521 8192000000 10166507872 10575704064 1276570080 13.482 100 29 57 1 1290398400 5934736 7893584
23:34:32 74297 8192000000 10168862176 10587721728 1276895968 13.485 99 31 62 0 1290095536 5330240 7869328
23:36:47 8344 8192000000 10196115952 10610466816 1299798096 13.693 100 33 66 3 1313924112 6411696 7714320
23:37:59 6601 8192000000 10206184160 10619826176 1311020240 13.795 100 22 44 1 1321132192 5115280 4996672
23:39:18 19895 8192000000 10249059600 10655162368 1329832320 13.966 100 51 101 0 1351467040 10541344 11093376
23:40:45 25318 8192000000 10264178128 10673602560 1347132320 14.122 100 58 114 4 1381227632 19336928 14758384
23:42:17 46451 8192000000 10263676752 10685673472 1353225504 14.176 100 42 84 1 1379206368 15844352 10136512
23:44:09 16136 8192000000 10273554208 10695102464 1364622736 14.279 100 40 81 0 1388382336 13693792 10065808
23:45:28 11600 8192000000 10296787568 10717212672 1375619456 14.377 100 51 101 1 1404749312 17454224 11675632
23:46:43 23944 8192000000 10321212528 10741600256 1393213920 14.535 100 39 76 0 1423871056 21129520 9527616
23:48:11 7087 8192000000 10343806784 10763956224 1410340720 14.687 100 63 126 1 1451443296 25794864 15307712
23:49:28 7264 8192000000 10347821600 10769678336 1411500480 14.697 100 57 113 1 1455906208 30047248 14358480
23:50:42 3990 8192000000 10334855824 10743435264 1388502096 14.492 100 78 152 1 1441802608 34893568 18406944
23:51:50 3657 8192000000 10353694864 10767507456 1403035984 14.622 100 82 164 0 1460877536 37542432 20299120
23:52:58 3134 8192000000 10337003456 10745049088 1377663040 14.396 99 85 171 2 1435016896 36117536 21236320
23:54:05 3909 8192000000 10326045200 10733350912 1363734176 14.271 98 75 149 0 1414316832 32718080 17864576
23:55:13 2527 8192000000 10336568016 10743095296 1382300976 14.437 100 88 174 1 1442434112 37829504 22303632
23:56:20 4413 8192000000 10328965616 10757578752 1392303488 14.526 100 43 86 1 1437255184 32916336 12035360
23:57:29 5901 8192000000 10360561744 10774949888 1402206768 14.615 100 72 144 1 1459296112 38332528 18756816
23:58:36 12247 8192000000 10303416768 10734051328 1359585392 14.234 99 35 71 1 1407381200 37950448 9845360
23:59:56 4734 8192000000 10322386896 10739232768 1363143408 14.266 100 68 138 5 1425300912 43301216 18856288
00:01:07 19333 8192000000 10306839584 10742587392 1376700016 14.387 100 24 47 2 1418994224 35727344 6566864
00:02:37 29260 8192000000 10277053632 10723995648 1331938368 13.985 98 19 36 2 1369706352 32472128 5295856
00:04:35 23704 8192000000 10273261408 10714501120 1322055072 13.895 97 19 38 0 1356699280 30034480 4609728
00:06:40 26025 8192000000 10261240448 10712879104 1349702464 14.145 100 27 54 2 1377656944 21687808 6266672
00:08:22 38251 8192000000 10285020048 10726744064 1365426912 14.286 99 39 77 1 1384357536 10296080 8634544
00:10:01 59003 8192000000 10278901616 10732433408 1364662160 14.279 99 18 36 1 1386165456 17640544 3862752
00:12:14 59097 8192000000 10270566880 10727583744 1347288416 14.123 98 7 13 0 1362957392 14249904 1419072
00:14:23 120051 8192000000 10277973120 10728574976 1367414208 14.304 99 26 52 0 1381314240 7879008 6021024
00:17:45 64807 8192000000 10299510624 10751078400 1393469040 14.537 100 26 52 1 1413874928 14525088 5880800
00:20:25 21280 8192000000 10311258368 10762862592 1389710416 14.503 99 18 34 2 1410275392 16429312 4135664
00:21:50 66063 8192000000 10327109408 10784903168 1406554384 14.653 100 35 70 0 1438437360 23244272 8638704
00:23:59 42868 8192000000 10322042880 10796236800 1409211136 14.677 100 13 26 0 1436655024 24299600 3144288
00:25:43 67961 8192000000 10312356992 10792787968 1405177072 14.641 100 13 25 1 1435773808 27569760 3026976
00:28:04 48778 8192000000 10294299840 10755661824 1396695696 14.566 100 22 44 0 1410975856 8908224 5371936
00:29:59 53694 8192000000 10308527472 10767106048 1379187648 14.409 99 19 38 1 1396546112 13202016 4156448
00:31:53 67752 8192000000 10287703712 10730745856 1315111024 13.832 95 23 46 0 1333712000 13420416 5180560
00:34:01 81034 8192000000 10302609536 10745856000 1365376832 14.286 99 24 48 0 1389451376 18074064 6000480
00:36:25 126692 8192000000 10273649552 10721947648 1299326960 13.689 94 13 26 2 1325146832 22336416 3483456
00:39:32 106834 8192000000 10286098000 10738118656 1372849728 14.353 99 38 76 0 1392481440 11347056 8284656
00:42:23 11275 8192000000 10273147360 10709200896 1352367248 14.169 99 35 70 0 1367854000 7543616 7943136
00:43:35 13150 8192000000 10256081728 10708975616 1359292032 14.231 100 37 72 1 1371799904 5419344 7088528
00:44:50 74580 8192000000 10253646528 10706714624 1334159808 14.005 97 22 44 0 1351448016 11882816 5405392
00:47:07 57759 8192000000 10274230896 10727985152 1347152016 14.122 98 16 32 1 1363985152 12827552 4005584
00:49:06 51000 8192000000 10273094000 10742321152 1360580064 14.243 99 13 26 1 1381217856 17351136 3286656
00:51:00 21477 8192000000 10274586048 10723684352 1366917072 14.299 100 20 40 0 1381766912 10472800 4377040
00:52:25 53049 8192000000 10260011328 10713518080 1327204224 13.942 97 31 61 0 1351861200 16562496 8094480
00:54:19 49952 8192000000 10266527264 10703466496 1305143184 13.742 96 26 52 0 1319596496 8972448 5480864
00:56:23 64434 8192000000 10268308384 10714705920 1325502432 13.926 97 18 36 0 1342493152 12783440 4207280
00:58:41 39147 8192000000 10288866336 10728660992 1363160624 14.266 100 28 56 0 1381468912 12060000 6248288
01:00:33 54956 8192000000 10293149808 10737135616 1364935872 14.282 99 13 26 0 1381189040 12839440 3413728
01:02:28 60967 8192000000 10289095632 10727460864 1347767728 14.127 98 18 36 3 1362804112 10921984 4114400
01:04:30 95989 8192000000 10278301936 10729091072 1346981984 14.120 98 19 38 0 1360122464 8893152 4247328
01:07:09 59413 8192000000 10283063920 10746470400 1371672096 14.342 99 11 20 3 1390158112 15589760 2896256
01:09:11 39491 8192000000 10269377232 10730954752 1377695120 14.396 100 10 20 0 1386990976 7369568 1926288
01:10:55 42669 8192000000 10268321616 10725023744 1357245552 14.213 99 9 18 0 1366228864 6892112 2091200
01:12:37 34447 8192000000 10286895600 10738548736 1382476704 14.439 100 12 24 0 1394818944 9670656 2671584
01:14:13 52469 8192000000 10290243792 10741866496 1375311792 14.375 99 19 38 1 1388259712 8577920 4370000
01:16:14 43848 8192000000 10314898016 10755457024 1388116880 14.489 100 38 76 0 1407223392 9793440 9313072
01:18:01 79985 8192000000 10301206400 10755481600 1359313088 14.231 97 15 30 1 1375799056 13453600 3032368
01:20:24 80179 8192000000 10297898368 10744299520 1364103632 14.274 98 15 30 0 1379762448 12435664 3223152
01:22:45 21040 8192000000 10298644960 10739605504 1373009824 14.354 99 37 74 0 1391810960 10604016 8197120
01:24:07 30905 8192000000 10306894944 10746908672 1385998224 14.470 100 26 52 1 1402078192 10146656 5933312
01:25:43 118104 8192000000 10294234448 10734485504 1352320064 14.168 98 25 47 0 1366093776 8377808 5395904
01:28:42 42043 8192000000 10243438624 10695979008 1323690896 13.910 97 6 12 0 1335988368 11302448 995024
01:30:24 60211 8192000000 10247013024 10701737984 1330566272 13.972 98 17 34 0 1341391536 7203568 3621696
01:32:25 54652 8192000000 10252507040 10702016512 1353311648 14.177 99 18 35 1 1366456976 9044608 4100720
01:34:26 37171 8192000000 10244593568 10703507456 1342836304 14.083 99 19 38 0 1363243776 15938992 4468480
01:36:44 39207 8192000000 10257246112 10704478208 1330136208 13.968 97 21 41 2 1344828560 8956336 5736016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-04-2022 01:39:59 to 22-04-2022 01:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 74 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-04-2022 01:40:14 to 22-04-2022 01:40:16.

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          243414
MON$OLDEST_ACTIVE               245608
MON$OLDEST_SNAPSHOT             245608
MON$NEXT_TRANSACTION            245608
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                       2015232
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:1431: 
:1432: mscs-test-161.budzdorov.ru    Fri Apr 22 01:38:23 2022
:1433:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1434: 
:1435: 
:1436: mscs-test-161.budzdorov.ru    Fri Apr 22 01:38:23 2022
:1437:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1438: 
:1439: 
:1440: mscs-test-161.budzdorov.ru    Fri Apr 22 01:38:26 2022
:1441:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1442: 
:1443: 
:1444: mscs-test-161.budzdorov.ru    Fri Apr 22 01:38:30 2022
:1445:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1446: 
--- end of diff output ---

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

22.04.22 01:40:17. 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-04-22 01:40:20 No severe PSQL-related problems occured
22.04.22 01:40:21. End of report.