Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.10.2022 16: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 15374988 kB
MemAvailable 30510760 kB
Buffers 0 kB
Cached 16315240 kB
SwapCached 2108 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-10-09 16:38:29
DTS_END                         2022-10-09 16:38:29.6030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48758
TRN_ID                          237888
ATT_ID                          717
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: 9434.87 21868 1132184
customer order: creation 167.08 360 20050
customer order: refuse 13.91 408 1669
order to supplier: creation 44.93 1305 5391
order to supplier: removal 3.50 113119 420
invoice (draft): creation 43.83 3317 5259
invoice (draft): removal 55.60 972 6672
invoice accept: apply 40.23 18689 4827
invoice accept: cancel 50.22 782 6026
customer reserve: creation 3770.32 183 452438
customer reserve: removal 4945.65 60 593478
realization accept: apply 81.08 194 9730
realization accept: cancel 92.63 106 11115
payment from customer: creation 57.15 124 6858
payment from customer: removal 5.47 101 656
payment to supplier: creation 47.74 142 5729
payment to supplier: removal 3.43 100 411
service: total inventory turnovers 4.64 1181 557
service: total monetary turnovers 6.60 52 792
service: aggregate perf. data 0.85 17814 102
service: refresh index statistics 0.03 278360 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-10-2022 16:39:30 to 09-10-2022 16:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1842
TEST_TIME 2 4331
TEST_TIME 3 4431
TEST_TIME 4 5111
TEST_TIME 5 5155
TEST_TIME 6 5103
TEST_TIME 7 5337
TEST_TIME 8 5450
TEST_TIME 9 5670
TEST_TIME 10 5922
TEST_TIME 11 6204
TEST_TIME 12 6252
TEST_TIME 13 6555
TEST_TIME 14 6588
TEST_TIME 15 6602
TEST_TIME 16 6595
TEST_TIME 17 6732
TEST_TIME 18 6781
TEST_TIME 19 7232
TEST_TIME 20 8319
TEST_TIME 21 8169
TEST_TIME 22 8175
TEST_TIME 23 8312
TEST_TIME 24 8325
TEST_TIME 25 8426
TEST_TIME 26 8468
TEST_TIME 27 9540
TEST_TIME 28 9920
TEST_TIME 29 10847
TEST_TIME 30 11020
TEST_TIME 31 10990
TEST_TIME 32 10704
TEST_TIME 33 10551
TEST_TIME 34 10591
TEST_TIME 35 10622
TEST_TIME 36 10474
TEST_TIME 37 10447
TEST_TIME 38 10419
TEST_TIME 39 10289
TEST_TIME 40 10199
TEST_TIME 41 10088
TEST_TIME 42 9927
TEST_TIME 43 9876
TEST_TIME 44 9827
TEST_TIME 45 9850
TEST_TIME 46 9760
TEST_TIME 47 9696
TEST_TIME 48 9683
TEST_TIME 49 9599
TEST_TIME 50 9560
TEST_TIME 51 9466
TEST_TIME 52 9407
TEST_TIME 53 9302
TEST_TIME 54 9325
TEST_TIME 55 9524
TEST_TIME 56 9503
TEST_TIME 57 9495
TEST_TIME 58 9601
TEST_TIME 59 9575
TEST_TIME 60 9600
TEST_TIME 61 9521
TEST_TIME 62 9599
TEST_TIME 63 9611
TEST_TIME 64 9590
TEST_TIME 65 9581
TEST_TIME 66 9713
TEST_TIME 67 9737
TEST_TIME 68 9763
TEST_TIME 69 9780
TEST_TIME 70 9880
TEST_TIME 71 9750
TEST_TIME 72 9710
TEST_TIME 73 9704
TEST_TIME 74 9673
TEST_TIME 75 9622
TEST_TIME 76 9720
TEST_TIME 77 9708
TEST_TIME 78 9676
TEST_TIME 79 9659
TEST_TIME 80 9637
TEST_TIME 81 9578
TEST_TIME 82 9518
TEST_TIME 83 9419
TEST_TIME 84 9500
TEST_TIME 85 9510
TEST_TIME 86 9501
TEST_TIME 87 9558
TEST_TIME 88 9516
TEST_TIME 89 9543
TEST_TIME 90 9570
TEST_TIME 91 9554
TEST_TIME 92 9591
TEST_TIME 93 9678
TEST_TIME 94 9680
TEST_TIME 95 9671
TEST_TIME 96 9770
TEST_TIME 97 9726
TEST_TIME 98 9742
TEST_TIME 99 9694
TEST_TIME 100 9708
TEST_TIME 101 9659
TEST_TIME 102 9615
TEST_TIME 103 9573
TEST_TIME 104 9514
TEST_TIME 105 9551
TEST_TIME 106 9555
TEST_TIME 107 9548
TEST_TIME 108 9545
TEST_TIME 109 9512
TEST_TIME 110 9485
TEST_TIME 111 9475
TEST_TIME 112 9431
TEST_TIME 113 9373
TEST_TIME 114 9406
TEST_TIME 115 9456
TEST_TIME 116 9457
TEST_TIME 117 9431
TEST_TIME 118 9467
TEST_TIME 119 9483
TEST_TIME 120 9437

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-10-2022 16:39:33 to 09-10-2022 16:39:37.

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 1551862 1551862 0 0.00 0 15493 6 0 0
doc_list_biud 1551862 1551862 0 0.00 0 57679 30 0 0
sp_add_invoice_to_stock 4975 4827 148 2.97 48 202467 18690 0 148
sp_cancel_adding_invoice 6026 6026 0 0.00 5 89044 783 0 0
sp_cancel_client_order 1669 1669 0 0.00 4 19941 409 0 0
sp_cancel_customer_reserve 593478 593478 0 0.00 0 30502 61 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 2 4635 102 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 4524 101 0 0
sp_cancel_supplier_invoice 6684 6672 12 0.17 2 57686 972 0 12
sp_cancel_supplier_order 420 420 0 0.00 6 1167581 113119 0 0
sp_cancel_write_off 11115 11115 0 0.00 1 24903 106 0 0
sp_client_order 20050 20050 0 0.00 3 31167 361 0 0
sp_customer_reserve 452438 452438 0 0.00 0 57768 184 0 0
sp_fill_shopping_cart 30689 30689 0 0.00 1 14371 40 0 0
sp_fill_shopping_cart_clo_res 452430 452430 0 0.00 0 18100 14 0 0
sp_get_clo_for_invoice 4820 4820 0 0.00 0 33577 327 0 0
sp_kill_cost_storno 18208 18208 0 0.00 0 15488 61 0 0
sp_kill_qstorno_ret_qs2qd 1205163 1205163 0 0.00 0 57669 34 0 0
sp_kill_qty_storno 633930 633930 0 0.00 0 57675 72 0 0
sp_lock_selected_doc 20134 20134 0 0.00 0 11296 6 0 0
sp_make_cost_storno 27137 27137 0 0.00 0 13728 55 0 0
sp_multiply_rows_for_pdistr 27137 27137 0 0.00 0 10420 30 0 0
sp_multiply_rows_for_qdistr 20050 20050 0 0.00 0 25686 157 0 0
sp_pay_from_customer 6858 6858 0 0.00 4 13849 125 0 0
sp_pay_to_supplier 5729 5729 0 0.00 5 14149 142 0 0
sp_payment_common 12587 12587 0 0.00 1 13499 36 0 0
sp_qd_handle_on_cancel_clo 1669 1669 0 0.00 0 19929 375 0 0
sp_qd_handle_on_invoice_upd_sts 10846 10846 0 0.00 0 25282 333 0 0
sp_qd_handle_on_reserve_upd_sts 9730 9730 0 0.00 0 25234 68 0 0
sp_reserve_write_off 9730 9730 0 0.00 6 25287 194 0 0
sp_supplier_invoice 5259 5259 0 0.00 5 92231 3318 0 0
sp_supplier_order 5391 5391 0 0.00 4 46692 1306 0 0
srv_aggregate_perf_data 103 102 1 0.97 2877 185486 17814 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1219 116825 43825 0 0
srv_find_qd_qs_mism 1086077 1086077 0 0.00 0 14024 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 47 107796 1181 0 0
srv_make_money_saldo 792 792 0 0.00 1 11848 53 0 0
srv_recalc_idx_stat 4 4 0 0.00 157543 428674 278361 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4827 4827 0 0.00 0 11003 187 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 0 12135 109 0 0
t$perf-norm:sp_cancel_client_order 1669 1669 0 0.00 0 2727 15 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 2656 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 856 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 3759 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 1224 15 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 34933 1549 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 3401 15 0 0
t$perf-norm:sp_client_order 20050 20050 0 0.00 0 6862 18 0 0
t$perf-norm:sp_customer_reserve 896 896 0 0.00 0 4820 14 0 0
t$perf-norm:sp_pay_from_customer 6858 6858 0 0.00 0 7014 9 0 0
t$perf-norm:sp_pay_to_supplier 5729 5729 0 0.00 0 8673 15 0 0
t$perf-norm:sp_reserve_write_off 9730 9730 0 0.00 0 5701 13 0 0
t$perf-norm:sp_supplier_invoice 5259 5259 0 0.00 0 8308 18 0 0
t$perf-norm:sp_supplier_order 5391 5391 0 0.00 0 7087 17 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 110 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 79 3 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 1512 27 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 6525 29 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9932 9636 296 2.98 0 5971 19 0 296
v_all_customers 36630 36630 0 0.00 0 21 1 0 0
v_all_suppliers 29708 29708 0 0.00 0 61 1 0 0
v_all_wares 40100 40100 0 0.00 0 71 1 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 2635 11 0 0
v_cancel_client_order 3338 3338 0 0.00 0 4163 9 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 4474 14 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 569 4 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 1329 5 0 0
v_cancel_supplier_invoice 766 742 24 3.13 0 2207 18 0 24
v_cancel_supplier_order 840 840 0 0.00 0 1411 7 0 0
v_cancel_write_off 1996 1996 0 0.00 0 7617 20 0 0
v_max_id_clo_ord 5388 5388 0 0.00 0 4726 3 0 0
v_max_id_clo_res 895 895 0 0.00 0 2976 14 0 0
v_max_id_ord_sup 5251 5251 0 0.00 0 4274 3 0 0
v_max_non_paid_invoice 1513 1513 0 0.00 0 2146 4 0 0
v_max_non_paid_realizn 3583 3583 0 0.00 0 5759 9 0 0
v_min_id_clo_ord 5388 5388 0 0.00 0 7360 9 0 0
v_min_id_clo_res 895 895 0 0.00 0 6017 18 0 0
v_min_id_ord_sup 5251 5251 0 0.00 0 6584 10 0 0
v_min_non_paid_invoice 5729 5729 0 0.00 0 11340 26 0 0
v_min_non_paid_realizn 6858 6858 0 0.00 0 11178 28 0 0
v_our_firm 10020 10020 0 0.00 0 25 1 0 0
v_reserve_write_off 19460 19460 0 0.00 0 7083 8 0 0
x_lock_depdocs_on_canc_invoice 12327 12327 0 0.00 0 17679 134 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 11400 366 0 0
x_make_qstorno_1000_1200 5391 5391 0 0.00 1 46675 1227 0 0
x_make_qstorno_1200_2000 5259 5259 0 0.00 2 92220 3173 0 0
x_make_qstorno_2100_3300 452438 452438 0 0.00 0 57767 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-10-2022 16:39:38 to 09-10-2022 16:39:42.

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
14:08:33 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:34 34631 8192000000 9911336688 10222514176 991038960 10.792 100 86 172 0 1019701968 5830368 22832640
14:11:15 8108 8192000000 9955443888 10275205120 1037791488 11.243 100 91 182 0 1068988528 8060864 23136176
14:12:27 14249 8192000000 9989211808 10318712832 1072671568 11.578 100 91 182 1 1104945168 9193792 23079808
14:13:46 11783 8192000000 10021925328 10370281472 1112235552 11.954 100 69 136 0 1137976480 9225792 16515136
14:15:09 6504 8192000000 10073981424 10434293760 1165543408 12.455 100 68 136 1 1192967376 11226256 16197712
14:16:18 2261 8192000000 10116561776 10483765248 1207869936 12.849 100 85 167 0 1240010752 13544336 18596480
14:17:22 2690 8192000000 10151612880 10527576064 1248419536 13.224 100 85 168 2 1279186192 12593136 18173520
14:18:27 2750 8192000000 10188175152 10570727424 1280356432 13.516 100 79 158 0 1311510752 14411872 16742448
14:19:31 900 8192000000 10222670448 10609287168 1304790672 13.739 100 89 179 0 1337439424 12893360 19755392
14:20:33 691 8192000000 10224862176 10613317632 1312970528 13.813 100 88 178 0 1344535104 10727040 20837536
14:21:34 11825 8192000000 10195808128 10613833728 1312151280 13.806 100 17 34 0 1321842608 6038112 3653216
14:22:47 5517 8192000000 10207855488 10622427136 1322256864 13.897 100 27 53 0 1334439568 6087040 6095664
14:23:52 9086 8192000000 10213499760 10631684096 1325680272 13.928 100 22 42 0 1336967360 6312976 4974112
14:25:04 20261 8192000000 10221554608 10641137664 1335171104 14.014 100 17 34 0 1345518864 6417888 3929872
14:26:25 23068 8192000000 10229726512 10654187520 1338433856 14.043 100 22 41 0 1349331456 6411664 4485936
14:27:50 8156 8192000000 10219805024 10640949248 1310796096 13.793 98 21 42 0 1321203200 5364912 5042192
14:28:58 6451 8192000000 10219844912 10639278080 1318045408 13.859 99 16 31 0 1326808224 5219920 3542896
14:30:08 17610 8192000000 10148654304 10557779968 1215037824 12.916 94 12 24 0 1222221216 4763200 2420192
14:31:26 8174 8192000000 10106948016 10507550720 1181264768 12.602 95 21 42 2 1196183472 9777456 5141248
14:32:35 6618 8192000000 10144112912 10549583872 1244371040 13.186 99 24 47 1 1255867008 5306240 6189728
14:33:42 6547 8192000000 10159883232 10568327168 1270842384 13.429 100 28 56 0 1283388032 6088112 6457536
14:34:55 50312 8192000000 10181701712 10590826496 1291241232 13.616 100 34 67 0 1305076032 5483664 8351136
14:37:05 4996 8192000000 10190393264 10605961216 1303339152 13.726 100 33 68 1 1316770608 5582656 7848800
14:38:10 11149 8192000000 10211342304 10626379776 1319117024 13.869 100 20 39 0 1328193904 4874000 4202880
14:39:30 23648 8192000000 10247608928 10655666176 1335879248 14.020 100 42 82 1 1354014464 8802528 9332688
14:40:57 71889 8192000000 10268344480 10694656000 1361384832 14.250 100 52 105 1 1387833952 14081392 12367728
14:43:19 3420 8192000000 10290272016 10710315008 1380096064 14.417 100 56 112 0 1408537840 15799888 12641888
14:44:28 11161 8192000000 10295396288 10717974528 1375768432 14.379 99 43 86 0 1402344720 16861920 9714368
14:45:42 5330 8192000000 10305204992 10725773312 1388026496 14.488 100 51 97 5 1417147056 17642112 11478448
14:46:53 1216 8192000000 10315427680 10730520576 1384323968 14.455 99 74 146 0 1420920768 19033616 17563184
14:47:59 4010 8192000000 10316640608 10731282432 1376176032 14.382 99 63 126 2 1412156432 21569296 14411104
14:49:06 2723 8192000000 10344266512 10765168640 1408258576 14.668 100 61 120 1 1451465504 28860400 14346528
14:50:22 5773 8192000000 10340988112 10761953280 1408220096 14.668 100 65 127 0 1452045216 28959472 14865648
14:51:32 3226 8192000000 10336332528 10747150336 1392684560 14.530 100 72 143 0 1441072560 30552720 17835280
14:52:37 3076 8192000000 10359353520 10776543232 1413303728 14.713 100 62 121 2 1465399808 37114496 14981584
14:53:48 6851 8192000000 10344979312 10755334144 1389435072 14.501 100 71 140 0 1450774064 43813600 17525392
14:54:58 3145 8192000000 10350215488 10754818048 1385965248 14.470 100 78 156 1 1456627504 50538352 20123904
14:56:06 5382 8192000000 10341203328 10753060864 1376761776 14.388 99 60 116 0 1447261280 55229312 15270192
14:57:23 3659 8192000000 10372773280 10785669120 1394919168 14.550 100 72 143 1 1475011760 61546928 18545664
14:58:37 8226 8192000000 10364084608 10795036672 1412468288 14.706 100 40 80 0 1473170320 49794432 10907600
15:00:00 3942 8192000000 10354206528 10766295040 1379508112 14.412 99 55 110 0 1439497376 45626624 14362640
15:01:16 16847 8192000000 10358612560 10787504128 1400295088 14.598 100 37 73 1 1464424112 53840784 10288240
15:02:35 15833 8192000000 10346624704 10773471232 1392319664 14.527 100 36 72 0 1460094416 57226656 10548096
15:03:54 10169 8192000000 10344499184 10768863232 1358160176 14.221 99 37 71 0 1436436976 67118560 11158240
15:05:08 15976 8192000000 10359011440 10788999168 1378639088 14.404 100 29 54 1 1456930464 69702976 8588400
15:06:26 30220 8192000000 10323867392 10770194432 1377300528 14.392 100 25 50 0 1432515968 47971168 7244272
15:08:11 50655 8192000000 10292978464 10732810240 1356085808 14.202 98 12 24 1 1381944944 23484240 2374896
15:10:02 87569 8192000000 10300443648 10734321664 1374567424 14.368 99 47 94 1 1394240608 9048864 10624320
15:12:31 38170 8192000000 10262250368 10714157056 1341728000 14.073 98 22 44 1 1357334608 10181072 5425536
15:14:10 48029 8192000000 10291652736 10744623104 1380217856 14.418 99 14 28 0 1392749248 8819184 3712208
15:16:03 74727 8192000000 10264792768 10721411072 1357390912 14.214 98 10 20 0 1368717072 9179136 2147024
15:18:20 35403 8192000000 10296399344 10745769984 1390182912 14.507 100 25 51 1 1402044960 6619008 5243040
15:20:38 69660 8192000000 10294516848 10745241600 1384988896 14.461 99 21 42 0 1405035904 14947776 5099232
15:22:48 84688 8192000000 10293775328 10753843200 1390343936 14.509 100 26 50 0 1407469968 11288112 5837920
15:25:13 55685 8192000000 10296788080 10760220672 1383800976 14.451 99 17 34 0 1402916688 15755120 3360592
15:27:14 20797 8192000000 10301398816 10748186624 1381385968 14.429 99 53 103 0 1400685648 7959296 11340384
15:28:36 58751 8192000000 10310478112 10769469440 1399476880 14.590 100 17 34 0 1420275008 16793712 4004416
15:30:43 71187 8192000000 10311489616 10774335488 1406516784 14.653 100 11 22 0 1431813984 22437600 2859600
15:33:53 48923 8192000000 10275716960 10732400640 1321388912 13.889 95 12 24 0 1336234528 12388736 2456880
15:35:56 82710 8192000000 10289162112 10737664000 1337839808 14.038 97 8 16 0 1352938720 13302752 1796160
15:38:19 85500 8192000000 10286566704 10733101056 1325625168 13.928 95 17 32 1 1337955008 8855904 3473936
15:40:45 59844 8192000000 10287181280 10740248576 1346608848 14.117 97 15 30 0 1358573712 8954384 3010480
15:42:45 33470 8192000000 10291993824 10734956544 1358250048 14.222 98 36 72 0 1381519200 15650320 7618832
15:44:20 109533 8192000000 10279195888 10746830848 1316427088 13.844 95 18 36 0 1336894592 16791760 3675744
15:47:13 68793 8192000000 10298080384 10755624960 1351604112 14.162 98 17 34 0 1382321104 26289056 4427936
15:49:31 58587 8192000000 10272672464 10721148928 1347503680 14.125 98 35 68 2 1361879872 6468400 7907792
15:51:30 40693 8192000000 10304401040 10746052608 1369976000 14.327 99 27 54 0 1387927440 12232656 5718784
15:53:11 41545 8192000000 10287780880 10739851264 1370272272 14.329 99 27 54 0 1396752272 20705040 5774960
15:54:55 22799 8192000000 10267023456 10726379520 1358907600 14.228 99 42 78 3 1373349056 5163264 9278192
15:56:24 58049 8192000000 10277141184 10720428032 1351649920 14.162 99 24 48 0 1364866352 8211360 5005072
15:58:25 45226 8192000000 10262639872 10724261888 1354076224 14.184 99 7 14 0 1367427952 11753024 1598704
16:00:33 87060 8192000000 10253858272 10711085056 1344642880 14.099 98 13 26 0 1362930464 15282800 3004784
16:03:05 58460 8192000000 10276749008 10737119232 1350965872 14.156 98 15 28 0 1365423584 11796208 2661504
16:05:13 44997 8192000000 10241205120 10699759616 1307752448 13.766 96 19 38 0 1319951024 8000592 4197984
16:06:59 15982 8192000000 10247454880 10686369792 1311996368 13.804 97 39 78 1 1330622832 10426160 8200304
16:08:18 85102 8192000000 10275294288 10725732352 1340013504 14.058 98 21 42 0 1355865504 11183872 4668128
16:10:50 86534 8192000000 10293585456 10757726208 1389990096 14.506 100 12 24 0 1407166240 14573168 2602976
16:13:17 46108 8192000000 10308591120 10760998912 1375484496 14.376 98 10 20 0 1390717616 12694784 2538336
16:15:07 71784 8192000000 10323812512 10769657856 1382188320 14.436 99 29 56 0 1399392464 10989952 6214192
16:17:21 116825 8192000000 10279397568 10738958336 1348963696 14.138 97 33 65 0 1371882176 15516192 7402288
16:20:28 95641 8192000000 10280126480 10740183040 1358641456 14.225 98 13 26 0 1376366416 14412656 3312304
16:23:04 66756 8192000000 10288090144 10748993536 1385704208 14.468 100 15 32 0 1403021536 13517264 3800064
16:25:30 69253 8192000000 10299004112 10744082432 1369871232 14.326 98 19 36 0 1382763520 8699184 4193104
16:27:42 40157 8192000000 10280936464 10733490176 1335441936 14.016 96 28 52 1 1353548880 11990064 6116880
16:29:38 51795 8192000000 10277263696 10736275456 1373654176 14.360 99 18 36 0 1398833312 20668704 4510432
16:31:51 69401 8192000000 10290442432 10762063872 1377146608 14.391 99 10 20 0 1393184640 13971552 2066480
16:34:12 90804 8192000000 10311202736 10751819776 1367861024 14.308 98 16 32 0 1380972048 9417120 3693904
16:36:50 37359 8192000000 10272815504 10726051840 1339134640 14.050 97 16 32 0 1352879808 10098992 3646176
16:38:28 7827 8192000000 9088222960 9320620032 253809104 3.005 19 1 2 0 256487040 2635584 42352

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-10-2022 16:39:44 to 09-10-2022 16:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 148 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-10-2022 16:40:01 to 09-10-2022 16:40:03.

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          238176
MON$OLDEST_ACTIVE               238177
MON$OLDEST_SNAPSHOT             238177
MON$NEXT_TRANSACTION            238177
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                       2044704
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
--- end of diff output ---

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

09.10.22 16:40:04. 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-10-09 16:40:06 No severe PSQL-related problems occured
09.10.22 16:40:08. End of report.