Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.07.2022 22: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 16148236 kB
MemAvailable 31162208 kB
Buffers 0 kB
Cached 15596648 kB
SwapCached 2520 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-07-09 22:38:22
DTS_END                         2022-07-09 22:38:22.8120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42260
TRN_ID                          251551
ATT_ID                          667
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: 9348.76 21506 1121851
customer order: creation 185.13 327 22215
customer order: refuse 16.38 386 1965
order to supplier: creation 49.08 1164 5889
order to supplier: removal 3.47 85436 416
invoice (draft): creation 49.42 3270 5930
invoice (draft): removal 50.83 722 6100
invoice accept: apply 44.42 17117 5330
invoice accept: cancel 45.78 763 5493
customer reserve: creation 4086.50 170 490380
customer reserve: removal 4515.54 51 541865
realization accept: apply 90.37 174 10844
realization accept: cancel 73.55 114 8826
payment from customer: creation 62.91 109 7549
payment from customer: removal 6.24 78 749
payment to supplier: creation 52.92 122 6350
payment to supplier: removal 3.78 72 453
service: total inventory turnovers 4.62 1305 554
service: total monetary turnovers 6.80 22 816
service: aggregate perf. data 1.03 18611 123
service: refresh index statistics 0.03 300114 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-07-2022 22:40:05 to 09-07-2022 22:40:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3201
TEST_TIME 2 2548
TEST_TIME 3 4143
TEST_TIME 4 4121
TEST_TIME 5 4089
TEST_TIME 6 4602
TEST_TIME 7 4946
TEST_TIME 8 5513
TEST_TIME 9 5489
TEST_TIME 10 6349
TEST_TIME 11 6537
TEST_TIME 12 6748
TEST_TIME 13 6792
TEST_TIME 14 6709
TEST_TIME 15 6663
TEST_TIME 16 6903
TEST_TIME 17 7291
TEST_TIME 18 7719
TEST_TIME 19 7824
TEST_TIME 20 8489
TEST_TIME 21 8543
TEST_TIME 22 9047
TEST_TIME 23 8835
TEST_TIME 24 8707
TEST_TIME 25 8918
TEST_TIME 26 9026
TEST_TIME 27 8890
TEST_TIME 28 9410
TEST_TIME 29 9858
TEST_TIME 30 9956
TEST_TIME 31 10058
TEST_TIME 32 10233
TEST_TIME 33 10029
TEST_TIME 34 10130
TEST_TIME 35 10018
TEST_TIME 36 9838
TEST_TIME 37 9618
TEST_TIME 38 9639
TEST_TIME 39 9821
TEST_TIME 40 9662
TEST_TIME 41 9695
TEST_TIME 42 9636
TEST_TIME 43 9586
TEST_TIME 44 9570
TEST_TIME 45 9567
TEST_TIME 46 9517
TEST_TIME 47 9547
TEST_TIME 48 9499
TEST_TIME 49 9466
TEST_TIME 50 9384
TEST_TIME 51 9336
TEST_TIME 52 9260
TEST_TIME 53 9353
TEST_TIME 54 9388
TEST_TIME 55 9439
TEST_TIME 56 9355
TEST_TIME 57 9547
TEST_TIME 58 9485
TEST_TIME 59 9542
TEST_TIME 60 9501
TEST_TIME 61 9556
TEST_TIME 62 9601
TEST_TIME 63 9652
TEST_TIME 64 9657
TEST_TIME 65 9621
TEST_TIME 66 9524
TEST_TIME 67 9562
TEST_TIME 68 9595
TEST_TIME 69 9538
TEST_TIME 70 9538
TEST_TIME 71 9473
TEST_TIME 72 9430
TEST_TIME 73 9469
TEST_TIME 74 9440
TEST_TIME 75 9402
TEST_TIME 76 9353
TEST_TIME 77 9431
TEST_TIME 78 9415
TEST_TIME 79 9403
TEST_TIME 80 9454
TEST_TIME 81 9407
TEST_TIME 82 9451
TEST_TIME 83 9526
TEST_TIME 84 9518
TEST_TIME 85 9520
TEST_TIME 86 9618
TEST_TIME 87 9614
TEST_TIME 88 9630
TEST_TIME 89 9603
TEST_TIME 90 9569
TEST_TIME 91 9523
TEST_TIME 92 9503
TEST_TIME 93 9476
TEST_TIME 94 9440
TEST_TIME 95 9388
TEST_TIME 96 9434
TEST_TIME 97 9459
TEST_TIME 98 9493
TEST_TIME 99 9520
TEST_TIME 100 9495
TEST_TIME 101 9588
TEST_TIME 102 9562
TEST_TIME 103 9543
TEST_TIME 104 9533
TEST_TIME 105 9611
TEST_TIME 106 9591
TEST_TIME 107 9555
TEST_TIME 108 9626
TEST_TIME 109 9633
TEST_TIME 110 9595
TEST_TIME 111 9548
TEST_TIME 112 9526
TEST_TIME 113 9489
TEST_TIME 114 9467
TEST_TIME 115 9490
TEST_TIME 116 9491
TEST_TIME 117 9461
TEST_TIME 118 9431
TEST_TIME 119 9404
TEST_TIME 120 9356

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-07-2022 22:40:08 to 09-07-2022 22:40:12.

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 1574771 1574771 0 0.00 0 15251 6 0 0
doc_list_biud 1574758 1574758 0 0.00 0 31078 23 0 0
sp_add_invoice_to_stock 5413 5330 83 1.53 23 175481 17118 0 83
sp_cancel_adding_invoice 5493 5493 0 0.00 5 71991 764 0 0
sp_cancel_client_order 1965 1965 0 0.00 4 16553 386 0 0
sp_cancel_customer_reserve 541865 541865 0 0.00 0 31082 51 0 0
sp_cancel_pay_from_customer 749 749 0 0.00 2 1895 78 0 0
sp_cancel_pay_to_supplier 453 453 0 0.00 3 5969 73 0 0
sp_cancel_supplier_invoice 6104 6100 4 0.06 2 15445 722 0 4
sp_cancel_supplier_order 416 416 0 0.00 9 943016 85436 0 0
sp_cancel_write_off 8826 8826 0 0.00 1 15123 115 0 0
sp_client_order 22215 22215 0 0.00 3 32235 327 0 0
sp_customer_reserve 490380 490380 0 0.00 0 94566 170 0 0
sp_fill_shopping_cart 34034 34034 0 0.00 1 35072 38 0 0
sp_fill_shopping_cart_clo_res 490374 490374 0 0.00 0 15398 12 0 0
sp_get_clo_for_invoice 5323 5323 0 0.00 0 31177 264 0 0
sp_kill_cost_storno 15521 15521 0 0.00 0 5966 50 0 0
sp_kill_qstorno_ret_qs2qd 1099072 1099072 0 0.00 0 31076 28 0 0
sp_kill_qty_storno 580825 580825 0 0.00 0 31078 62 0 0
sp_lock_selected_doc 22413 22413 0 0.00 0 10404 7 0 0
sp_make_cost_storno 30066 30066 0 0.00 0 8312 47 0 0
sp_multiply_rows_for_pdistr 30056 30056 0 0.00 0 5095 24 0 0
sp_multiply_rows_for_qdistr 22215 22215 0 0.00 0 13113 148 0 0
sp_pay_from_customer 7549 7549 0 0.00 4 26392 110 0 0
sp_pay_to_supplier 6350 6350 0 0.00 4 35548 123 0 0
sp_payment_common 13895 13895 0 0.00 1 8712 27 0 0
sp_qd_handle_on_cancel_clo 1965 1965 0 0.00 0 16544 351 0 0
sp_qd_handle_on_invoice_upd_sts 10811 10811 0 0.00 0 22125 296 0 0
sp_qd_handle_on_reserve_upd_sts 10842 10842 0 0.00 0 22677 66 0 0
sp_reserve_write_off 10844 10844 0 0.00 6 22695 175 0 0
sp_supplier_invoice 5930 5930 0 0.00 7 92468 3271 0 0
sp_supplier_order 5889 5889 0 0.00 5 40906 1165 0 0
srv_aggregate_perf_data 123 123 0 0.00 2265 324574 18612 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1145 106657 43767 0 0
srv_find_qd_qs_mism 1070245 1070245 0 0.00 0 15250 6 0 0
srv_make_invnt_saldo 554 554 0 0.00 38 69493 1306 0 0
srv_make_money_saldo 816 816 0 0.00 1 1134 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 171803 644805 300114 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 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 5330 5330 0 0.00 0 9000 152 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 4960 96 0 0
t$perf-norm:sp_cancel_client_order 1965 1965 0 0.00 0 5028 15 0 0
t$perf-norm:sp_cancel_customer_reserve 747 747 0 0.00 0 1469 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 749 749 0 0.00 0 2261 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 453 453 0 0.00 0 3774 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 1060 13 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 22771 1554 0 0
t$perf-norm:sp_cancel_write_off 1080 1080 0 0.00 0 1655 9 0 0
t$perf-norm:sp_client_order 22215 22215 0 0.00 0 6231 17 0 0
t$perf-norm:sp_customer_reserve 1099 1099 0 0.00 0 3396 17 0 0
t$perf-norm:sp_pay_from_customer 7549 7549 0 0.00 0 6642 10 0 0
t$perf-norm:sp_pay_to_supplier 6350 6350 0 0.00 0 4330 8 0 0
t$perf-norm:sp_reserve_write_off 10844 10844 0 0.00 0 5981 12 0 0
t$perf-norm:sp_supplier_invoice 5930 5930 0 0.00 0 5414 17 0 0
t$perf-norm:sp_supplier_order 5889 5889 0 0.00 0 3522 14 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 168 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1735 50 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 2683 26 0 0
t$perf-norm:srv_make_money_saldo 816 816 0 0.00 0 2727 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10802 10636 166 1.53 0 9390 18 0 166
v_all_customers 39570 39570 0 0.00 0 140 0 0 0
v_all_suppliers 32370 32370 0 0.00 0 66 1 0 0
v_all_wares 44430 44430 0 0.00 0 70 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 2996 13 0 0
v_cancel_client_order 3930 3930 0 0.00 0 2923 4 0 0
v_cancel_customer_prepayment 1498 1498 0 0.00 0 1794 6 0 0
v_cancel_customer_reserve 1494 1494 0 0.00 0 463 3 0 0
v_cancel_payment_to_supplier 906 906 0 0.00 0 1566 5 0 0
v_cancel_supplier_invoice 854 846 8 0.93 0 2769 19 0 8
v_cancel_supplier_order 832 832 0 0.00 0 2966 8 0 0
v_cancel_write_off 2160 2160 0 0.00 0 2602 14 0 0
v_max_id_clo_ord 5889 5889 0 0.00 0 954 1 0 0
v_max_id_clo_res 1099 1099 0 0.00 0 1930 13 0 0
v_max_id_ord_sup 5930 5930 0 0.00 0 5523 5 0 0
v_max_non_paid_invoice 1982 1982 0 0.00 0 468 2 0 0
v_max_non_paid_realizn 4357 4357 0 0.00 0 3655 5 0 0
v_min_id_clo_ord 5889 5889 0 0.00 0 7010 12 0 0
v_min_id_clo_res 1099 1099 0 0.00 0 5445 15 0 0
v_min_id_ord_sup 5930 5930 0 0.00 0 6836 18 0 0
v_min_non_paid_invoice 6348 6348 0 0.00 0 6277 20 0 0
v_min_non_paid_realizn 7547 7547 0 0.00 0 5973 15 0 0
v_our_firm 11240 11240 0 0.00 0 27 1 0 0
v_reserve_write_off 21684 21684 0 0.00 0 2975 5 0 0
x_lock_depdocs_on_canc_invoice 11170 11170 0 0.00 0 17230 121 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 16295 462 0 0
x_make_qstorno_1000_1200 5889 5889 0 0.00 1 37432 1089 0 0
x_make_qstorno_1200_2000 5930 5930 0 0.00 3 89217 3127 0 0
x_make_qstorno_2100_3300 490380 490380 0 0.00 0 94564 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-07-2022 22:40:13 to 09-07-2022 22:40:17.

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
20:08:26 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:26 26132 8192000000 9909337120 10215485440 988090816 10.763 100 93 186 0 1018294128 5764960 24438352
20:10:58 22746 8192000000 9943824304 10264035328 1021673616 11.088 100 89 178 0 1052495152 7795488 23026048
20:12:25 11693 8192000000 9988849088 10318761984 1069572608 11.548 100 86 173 0 1100363344 9209424 21581312
20:13:41 11766 8192000000 10038205616 10382868480 1119257984 12.020 100 82 161 0 1149852032 11289840 19304208
20:14:55 8364 8192000000 10082063168 10440990720 1171101696 12.507 100 87 174 0 1205389360 13691504 20596160
20:16:06 3292 8192000000 10121026336 10488352768 1208272928 12.853 100 87 168 2 1241625776 14044080 19308768
20:17:11 1972 8192000000 10163260272 10536878080 1250363488 13.242 100 90 178 1 1283141248 13068928 19708832
20:18:16 1883 8192000000 10197458096 10576502784 1276507616 13.481 100 92 183 3 1310205248 13824464 19873168
20:19:19 26079 8192000000 10198470448 10595053568 1294762592 13.648 100 62 122 0 1317370096 9400000 13207504
20:21:00 1644 8192000000 10212153456 10611572736 1307538320 13.764 100 43 86 0 1326750016 9663536 9548160
20:22:02 6840 8192000000 10200171712 10612162560 1316015360 13.841 100 24 47 0 1327785888 6606640 5163888
20:23:10 9172 8192000000 10207308912 10628304896 1327267504 13.942 100 19 37 0 1337855216 5997904 4589808
20:24:22 58397 8192000000 10212691312 10637918208 1329646752 13.964 100 17 34 1 1338873536 5184368 4042416
20:26:21 7356 8192000000 10220351600 10641928192 1332578464 13.990 100 24 47 0 1344419248 6763488 5077296
20:27:31 9712 8192000000 10221605216 10639085568 1317767968 13.856 99 23 43 0 1332498112 9555680 5174464
20:28:42 71797 8192000000 10151174832 10549129216 1175214480 12.546 89 26 52 0 1186062176 4794784 6052912
20:30:55 5922 8192000000 10149078272 10558992384 1253611952 13.271 99 25 51 1 1265091792 5643312 5836528
20:32:01 6370 8192000000 10129475584 10523705344 1227272640 13.029 99 20 40 0 1241410432 9885056 4252736
20:33:08 9472 8192000000 10157423360 10556882944 1252641104 13.262 99 25 50 0 1264759184 5998096 6119984
20:34:23 13898 8192000000 10176268656 10568597504 1272418816 13.444 100 58 117 0 1292109312 6338032 13352464
20:35:37 9218 8192000000 10179670848 10591506432 1290619360 13.610 100 26 52 0 1303352512 6396784 6336368
20:36:47 7403 8192000000 10192825328 10607230976 1301527024 13.709 100 22 42 1 1312003424 5448880 5027520
20:37:57 8064 8192000000 10208741792 10619273216 1308883248 13.776 100 18 36 0 1317938688 5067728 3987712
20:39:08 79709 8192000000 10248846704 10661679104 1343577872 14.090 100 40 80 0 1359030976 6481440 8971664
20:41:30 18837 8192000000 10268487200 10688323584 1361616928 14.252 100 49 98 0 1383368704 10658656 11093120
20:42:55 23266 8192000000 10287344608 10710441984 1381363456 14.429 100 57 114 0 1405882592 10530944 13988192
20:44:23 24050 8192000000 10291010384 10719465472 1385106288 14.462 100 39 78 0 1408887408 13893968 9887152
20:45:54 14056 8192000000 10299525824 10732593152 1383540480 14.448 99 39 78 0 1405942704 12958000 9444224
20:47:11 8961 8192000000 10318641648 10740158464 1402628064 14.618 100 56 109 1 1430054608 14597392 12829152
20:48:27 7860 8192000000 10326623184 10753536000 1406353328 14.652 100 64 126 1 1440460400 18698032 15409040
20:49:39 9424 8192000000 10326432112 10756153344 1405669616 14.645 100 58 115 0 1438649024 19385072 13594336
20:50:56 17217 8192000000 10320496528 10745491456 1398721888 14.584 100 54 108 1 1430822464 18396320 13704256
20:52:23 7709 8192000000 10314337712 10736988160 1387825760 14.486 99 52 104 0 1421251712 20380336 13045616
20:53:35 9939 8192000000 10313644080 10737999872 1380151504 14.418 99 56 112 1 1417725008 23910976 13662528
20:54:57 3154 8192000000 10320273328 10731290624 1371142208 14.337 99 81 159 1 1417093024 27616272 18334544
20:56:04 1141 8192000000 10310577072 10713198592 1360531760 14.242 100 83 164 1 1410903120 29500976 20870384
20:57:09 4586 8192000000 10297267008 10706563072 1352282944 14.168 100 61 117 1 1398644704 31273984 15087776
20:58:19 8644 8192000000 10280321552 10696224768 1329407792 13.962 98 55 110 1 1373167872 30355168 13404912
20:59:31 5330 8192000000 10291728304 10702012416 1340009728 14.057 99 56 112 0 1390227600 36183312 14034560
21:00:41 13490 8192000000 10277043120 10700509184 1346430816 14.115 100 27 54 0 1381466640 28244992 6790832
21:02:04 12703 8192000000 10285312832 10705793024 1351072832 14.157 100 51 100 0 1396270912 33200992 11997088
21:03:27 15953 8192000000 10288400416 10716934144 1355501520 14.197 100 28 53 1 1397445264 34765712 7178032
21:04:51 13572 8192000000 10272531312 10702516224 1331398512 13.980 99 28 56 2 1373089856 34317984 7373360
21:06:06 20117 8192000000 10289032784 10717487104 1330812448 13.974 99 23 46 1 1383736448 46384176 6539824
21:08:00 50480 8192000000 10291730656 10717446144 1348572176 14.135 99 23 46 0 1371655024 17927936 5154912
21:09:52 15675 8192000000 10272969024 10707357696 1356958784 14.210 99 16 33 0 1380522176 19669952 3893440
21:11:09 40067 8192000000 10285573856 10715877376 1350978944 14.156 98 24 47 0 1365598112 9750848 4868320
21:12:55 58942 8192000000 10297057760 10734374912 1371102336 14.337 99 20 40 0 1384011536 8400000 4509200
21:14:58 95065 8192000000 10307566768 10756030464 1393478432 14.537 100 35 68 1 1419293824 17586448 8228944
21:17:35 88330 8192000000 10317414928 10759213056 1400624464 14.601 100 20 39 1 1417172048 11973312 4574272
21:20:06 34084 8192000000 10304757680 10760122368 1405719488 14.646 100 46 92 0 1425350240 8825808 10804944
21:21:42 45569 8192000000 10330435872 10776924160 1413729760 14.717 100 27 54 0 1434142384 14078960 6333664
21:24:02 106656 8192000000 10323027616 10776690688 1402263264 14.615 99 18 35 0 1423296560 16792848 4240448
21:26:49 56563 8192000000 10304320080 10774233088 1401536864 14.609 99 13 27 2 1414740864 10064464 3139536
21:28:54 38038 8192000000 10290905888 10760466432 1380291456 14.419 98 7 14 1 1388680496 7084480 1304560
21:30:33 36368 8192000000 10316527360 10761646080 1377458608 14.394 98 35 67 1 1395940272 10819216 7662448
21:32:10 32035 8192000000 10296374112 10759450624 1383078192 14.444 99 16 33 0 1401933904 15056816 3798896
21:33:50 62262 8192000000 10269384016 10717433856 1322066864 13.895 95 11 21 0 1339996448 15425504 2504080
21:36:37 87482 8192000000 10255974272 10702934016 1285674080 13.565 94 20 40 0 1300044032 9444256 4925696
21:39:06 21066 8192000000 10288369552 10725113856 1363965584 14.273 99 41 81 0 1384493664 11757728 8770352
21:40:27 59590 8192000000 10259027600 10697564160 1293181456 13.633 94 23 46 0 1307708672 9723312 4803904
21:42:37 32133 8192000000 10256590592 10698432512 1333043472 13.995 98 19 38 0 1348508768 11475600 3989696
21:44:10 79420 8192000000 10276585808 10726289408 1357166224 14.212 99 35 67 2 1377992160 12920272 7905664
21:46:31 39777 8192000000 10267547600 10712395776 1347584960 14.126 98 25 50 0 1362736992 9295616 5856416
21:48:17 35290 8192000000 10264055808 10717143040 1354065408 14.184 99 26 48 0 1369912560 9653200 6193952
21:49:53 56972 8192000000 10263598304 10704863232 1346924320 14.120 99 14 28 1 1359558016 9146832 3486864
21:51:52 62047 8192000000 10251914800 10710818816 1338623472 14.045 98 13 26 0 1354065840 12345232 3097136
21:54:02 61981 8192000000 10278122800 10737410048 1362830928 14.263 99 20 38 1 1380492224 12899456 4761840
21:56:16 28059 8192000000 10271649328 10722598912 1357020976 14.211 99 36 71 0 1373805728 9016400 7768352
21:57:48 87594 8192000000 10284401888 10740482048 1370335136 14.330 99 13 26 0 1383915008 10251920 3327952
22:00:16 70676 8192000000 10291640256 10758590464 1374920848 14.371 99 25 50 0 1397542304 17252480 5368976
22:03:36 98952 8192000000 10303445552 10764210176 1360879376 14.245 97 20 39 0 1381463056 15880080 4703600
22:06:15 45594 8192000000 10323336736 10764611584 1400365568 14.598 100 29 60 0 1417239408 10874736 5999104
22:08:03 86492 8192000000 10311601440 10764017664 1392184640 14.525 99 18 36 1 1407939536 12062976 3691920
22:10:30 20636 8192000000 10308574512 10769424384 1407413888 14.661 100 14 28 0 1421987136 11466240 3107008
22:11:53 74129 8192000000 10282511440 10737307648 1331384288 13.980 94 12 23 0 1339018304 5410960 2223056
22:14:07 87465 8192000000 10307393904 10774962176 1404511008 14.635 100 12 22 1 1420779472 13921792 2346672
22:16:35 75295 8192000000 10300452160 10760450048 1372806032 14.352 98 13 26 0 1390836768 15003488 3027248
22:18:51 37458 8192000000 10289969840 10746019840 1336519280 14.026 95 18 35 1 1354351200 13078736 4753184
22:20:35 16889 8192000000 10261466048 10697490432 1330706896 13.974 98 47 89 1 1349378352 8142208 10529248
22:21:56 31720 8192000000 10279389360 10708287488 1292558160 13.628 94 35 70 0 1309449856 9146736 7744960
22:23:36 74596 8192000000 10266143104 10714181632 1326227152 13.933 97 11 22 0 1346296400 17990208 2079040
22:25:55 51461 8192000000 10280503376 10732367872 1356409776 14.205 99 22 44 0 1375570928 14141712 5019440
22:27:51 96941 8192000000 10256946304 10702770176 1314302608 13.825 96 21 42 1 1328213792 9380400 4530784
22:30:58 90579 8192000000 10292166752 10746208256 1381374192 14.429 100 22 44 0 1399294928 13024096 4896640
22:33:51 59628 8192000000 10263598816 10721587200 1346579424 14.117 99 9 16 0 1362170736 14073424 1517888
22:35:56 55096 8192000000 10269850800 10721177600 1353426976 14.178 99 10 20 0 1365720800 10068112 2225712
22:37:54 39931 8192000000 9224896784 9585422336 347200816 4.065 26 4 8 0 358524608 10558992 764800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-07-2022 22:40:19 to 09-07-2022 22:40:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 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 09-07-2022 22:40:35 to 09-07-2022 22:40:37.

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          252512
MON$OLDEST_ACTIVE               253598
MON$OLDEST_SNAPSHOT             253598
MON$NEXT_TRANSACTION            253598
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                       2033824
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:198: 
:199: mscs-test-161.budzdorov.ru    Sat Jul  9 22:38:37 2022
:200:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:201: 
--- end of diff output ---

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

09.07.22 22:40:38. 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-07-09 22:40:40 No severe PSQL-related problems occured
09.07.22 22:40:41. End of report.