Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.07.2022 19: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 16172332 kB
MemAvailable 31096928 kB
Buffers 0 kB
Cached 15554236 kB
SwapCached 2480 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-16 19:38:24
DTS_END                         2022-07-16 19:38:24.1860
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60630
TRN_ID                          237892
ATT_ID                          654
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: 9332.40 24240 1119888
customer order: creation 162.67 365 19520
customer order: refuse 14.81 340 1777
order to supplier: creation 44.67 1233 5360
order to supplier: removal 3.52 106489 422
invoice (draft): creation 44.19 3268 5303
invoice (draft): removal 56.33 818 6759
invoice accept: apply 40.15 17755 4818
invoice accept: cancel 50.26 702 6031
customer reserve: creation 3737.87 175 448544
customer reserve: removal 4885.74 60 586289
realization accept: apply 80.32 166 9638
realization accept: cancel 87.41 97 10489
payment from customer: creation 56.66 114 6799
payment from customer: removal 5.85 58 702
payment to supplier: creation 46.96 126 5635
payment to supplier: removal 3.33 98 400
service: total inventory turnovers 4.71 998 565
service: total monetary turnovers 6.09 29 731
service: aggregate perf. data 0.85 16885 102
service: refresh index statistics 0.03 335033 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-07-2022 19:39:44 to 16-07-2022 19:39:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1708
TEST_TIME 2 1881
TEST_TIME 3 2700
TEST_TIME 4 2698
TEST_TIME 5 3142
TEST_TIME 6 3494
TEST_TIME 7 3538
TEST_TIME 8 3972
TEST_TIME 9 4494
TEST_TIME 10 4883
TEST_TIME 11 4988
TEST_TIME 12 5380
TEST_TIME 13 5338
TEST_TIME 14 5359
TEST_TIME 15 5463
TEST_TIME 16 6223
TEST_TIME 17 6462
TEST_TIME 18 6962
TEST_TIME 19 7165
TEST_TIME 20 7849
TEST_TIME 21 7773
TEST_TIME 22 8216
TEST_TIME 23 8462
TEST_TIME 24 8952
TEST_TIME 25 9482
TEST_TIME 26 9927
TEST_TIME 27 10917
TEST_TIME 28 10651
TEST_TIME 29 10496
TEST_TIME 30 10412
TEST_TIME 31 10278
TEST_TIME 32 10309
TEST_TIME 33 10187
TEST_TIME 34 10082
TEST_TIME 35 9961
TEST_TIME 36 9837
TEST_TIME 37 9758
TEST_TIME 38 9740
TEST_TIME 39 9718
TEST_TIME 40 9606
TEST_TIME 41 9509
TEST_TIME 42 9466
TEST_TIME 43 9493
TEST_TIME 44 9306
TEST_TIME 45 9322
TEST_TIME 46 9335
TEST_TIME 47 9342
TEST_TIME 48 9357
TEST_TIME 49 9340
TEST_TIME 50 9416
TEST_TIME 51 9445
TEST_TIME 52 9492
TEST_TIME 53 9474
TEST_TIME 54 9487
TEST_TIME 55 9471
TEST_TIME 56 9524
TEST_TIME 57 9647
TEST_TIME 58 9742
TEST_TIME 59 9614
TEST_TIME 60 9499
TEST_TIME 61 9488
TEST_TIME 62 9396
TEST_TIME 63 9453
TEST_TIME 64 9464
TEST_TIME 65 9398
TEST_TIME 66 9365
TEST_TIME 67 9296
TEST_TIME 68 9461
TEST_TIME 69 9432
TEST_TIME 70 9453
TEST_TIME 71 9442
TEST_TIME 72 9493
TEST_TIME 73 9463
TEST_TIME 74 9515
TEST_TIME 75 9524
TEST_TIME 76 9508
TEST_TIME 77 9608
TEST_TIME 78 9639
TEST_TIME 79 9685
TEST_TIME 80 9664
TEST_TIME 81 9599
TEST_TIME 82 9587
TEST_TIME 83 9600
TEST_TIME 84 9545
TEST_TIME 85 9589
TEST_TIME 86 9536
TEST_TIME 87 9511
TEST_TIME 88 9447
TEST_TIME 89 9466
TEST_TIME 90 9414
TEST_TIME 91 9422
TEST_TIME 92 9447
TEST_TIME 93 9519
TEST_TIME 94 9506
TEST_TIME 95 9516
TEST_TIME 96 9556
TEST_TIME 97 9575
TEST_TIME 98 9573
TEST_TIME 99 9622
TEST_TIME 100 9606
TEST_TIME 101 9584
TEST_TIME 102 9631
TEST_TIME 103 9651
TEST_TIME 104 9677
TEST_TIME 105 9644
TEST_TIME 106 9563
TEST_TIME 107 9531
TEST_TIME 108 9576
TEST_TIME 109 9576
TEST_TIME 110 9557
TEST_TIME 111 9517
TEST_TIME 112 9505
TEST_TIME 113 9485
TEST_TIME 114 9453
TEST_TIME 115 9425
TEST_TIME 116 9394
TEST_TIME 117 9378
TEST_TIME 118 9382
TEST_TIME 119 9387
TEST_TIME 120 9337

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-07-2022 19:39:47 to 16-07-2022 19:39:51.

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 1535628 1535628 0 0.00 0 22083 6 0 0
doc_list_biud 1535622 1535622 0 0.00 0 25890 29 0 0
sp_add_invoice_to_stock 4869 4818 51 1.04 24 154353 17756 0 51
sp_cancel_adding_invoice 6031 6031 0 0.00 4 61389 703 0 0
sp_cancel_client_order 1777 1777 0 0.00 5 9621 341 0 0
sp_cancel_customer_reserve 586289 586289 0 0.00 0 24386 61 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 5338 58 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 2 14880 98 0 0
sp_cancel_supplier_invoice 6766 6759 7 0.10 1 25907 818 0 7
sp_cancel_supplier_order 422 422 0 0.00 12 1119533 106489 0 0
sp_cancel_write_off 10489 10489 0 0.00 1 8067 97 0 0
sp_client_order 19520 19520 0 0.00 3 73909 365 0 0
sp_customer_reserve 448544 448544 0 0.00 0 94183 176 0 0
sp_fill_shopping_cart 30178 30178 0 0.00 1 29542 38 0 0
sp_fill_shopping_cart_clo_res 448526 448526 0 0.00 0 27285 14 0 0
sp_get_clo_for_invoice 4796 4796 0 0.00 0 21948 300 0 0
sp_kill_cost_storno 17622 17622 0 0.00 0 10887 50 0 0
sp_kill_qstorno_ret_qs2qd 1190248 1190248 0 0.00 0 25882 33 0 0
sp_kill_qty_storno 626199 626199 0 0.00 0 25888 70 0 0
sp_lock_selected_doc 20029 20029 0 0.00 0 7142 7 0 0
sp_make_cost_storno 26868 26868 0 0.00 0 13780 54 0 0
sp_multiply_rows_for_pdistr 26868 26868 0 0.00 0 8869 26 0 0
sp_multiply_rows_for_qdistr 19520 19520 0 0.00 0 73900 173 0 0
sp_pay_from_customer 6799 6799 0 0.00 4 28563 114 0 0
sp_pay_to_supplier 5635 5635 0 0.00 4 14200 126 0 0
sp_payment_common 12434 12434 0 0.00 1 9075 26 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 9615 312 0 0
sp_qd_handle_on_invoice_upd_sts 10825 10825 0 0.00 0 23063 290 0 0
sp_qd_handle_on_reserve_upd_sts 9638 9638 0 0.00 0 11694 63 0 0
sp_reserve_write_off 9638 9638 0 0.00 5 21701 167 0 0
sp_supplier_invoice 5303 5303 0 0.00 5 145852 3269 0 0
sp_supplier_order 5360 5360 0 0.00 4 45749 1234 0 0
srv_aggregate_perf_data 102 102 0 0.00 2527 131756 16886 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2737 135216 45666 0 0
srv_find_qd_qs_mism 1074155 1074155 0 0.00 0 22082 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 39 42640 999 0 0
srv_make_money_saldo 731 731 0 0.00 1 7048 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 259727 464394 335034 0 0
t$perf-abend:sp_add_invoice_to_stock 51 51 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4818 4818 0 0.00 0 12441 155 0 0
t$perf-norm:sp_cancel_adding_invoice 369 369 0 0.00 0 7393 109 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 5148 18 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 529 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 1539 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 222 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 6661 37 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 33980 1712 0 0
t$perf-norm:sp_cancel_write_off 900 900 0 0.00 0 3376 11 0 0
t$perf-norm:sp_client_order 19520 19520 0 0.00 0 12753 17 0 0
t$perf-norm:sp_customer_reserve 976 976 0 0.00 0 8686 15 0 0
t$perf-norm:sp_pay_from_customer 6799 6799 0 0.00 0 13871 12 0 0
t$perf-norm:sp_pay_to_supplier 5635 5635 0 0.00 0 3555 9 0 0
t$perf-norm:sp_reserve_write_off 9638 9638 0 0.00 0 14276 10 0 0
t$perf-norm:sp_supplier_invoice 5303 5303 0 0.00 0 7262 20 0 0
t$perf-norm:sp_supplier_order 5360 5360 0 0.00 0 9242 17 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 300 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 953 17 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 2193 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9688 9586 102 1.05 0 17190 19 0 102
v_all_customers 35438 35438 0 0.00 0 14 0 0 0
v_all_suppliers 29498 29498 0 0.00 0 18 1 0 0
v_all_wares 39040 39040 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 738 738 0 0.00 0 4070 13 0 0
v_cancel_client_order 3554 3554 0 0.00 0 4263 6 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 3779 6 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 1965 4 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 8497 14 0 0
v_cancel_supplier_invoice 760 746 14 1.84 0 636 12 0 14
v_cancel_supplier_order 844 844 0 0.00 0 4019 11 0 0
v_cancel_write_off 1800 1800 0 0.00 0 5759 16 0 0
v_max_id_clo_ord 5359 5359 0 0.00 0 168 1 0 0
v_max_id_clo_res 976 976 0 0.00 0 1720 11 0 0
v_max_id_ord_sup 5299 5299 0 0.00 0 5587 6 0 0
v_max_non_paid_invoice 1544 1544 0 0.00 0 4442 6 0 0
v_max_non_paid_realizn 3699 3699 0 0.00 0 261 3 0 0
v_min_id_clo_ord 5359 5359 0 0.00 0 3455 8 0 0
v_min_id_clo_res 976 976 0 0.00 0 5820 14 0 0
v_min_id_ord_sup 5299 5299 0 0.00 0 20546 29 0 0
v_min_non_paid_invoice 5635 5635 0 0.00 0 7342 21 0 0
v_min_non_paid_realizn 6799 6799 0 0.00 0 9071 17 0 0
v_our_firm 9802 9802 0 0.00 0 25 1 0 0
v_reserve_write_off 19276 19276 0 0.00 0 19755 5 0 0
x_lock_depdocs_on_canc_invoice 12417 12417 0 0.00 0 10773 101 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 9718 335 0 0
x_make_qstorno_1000_1200 5360 5360 0 0.00 1 45096 1166 0 0
x_make_qstorno_1200_2000 5303 5303 0 0.00 2 145575 3124 0 0
x_make_qstorno_2100_3300 448544 448544 0 0.00 0 94161 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-07-2022 19:39:52 to 16-07-2022 19:39:56.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:27 5 8192000000 8805584176 8819089408 10787728 0.131 3 3 4 0 10971632 76800 107104
17:09:29 13431 8192000000 9893081088 10204184576 979694320 10.681 100 82 163 0 1006135024 5662272 20778432
17:10:46 26432 8192000000 9957140304 10278932480 1036549024 11.231 100 92 184 0 1068299584 8333296 23417264
17:12:16 13714 8192000000 9973983440 10301407232 1054854752 11.407 100 86 170 1 1086538096 10238576 21444768
17:13:40 9624 8192000000 10030027776 10374541312 1115744048 11.987 100 84 168 0 1146779184 11274128 19761008
17:14:58 7480 8192000000 10069999024 10429562880 1162013680 12.422 100 73 144 1 1190336784 11777056 16546048
17:16:10 3630 8192000000 10124740288 10489974784 1206030000 12.832 100 88 174 1 1239233056 13056400 20146656
17:17:16 2615 8192000000 10152065440 10526916608 1238897232 13.136 100 78 157 0 1268234576 12118096 17219248
17:18:20 5576 8192000000 10182709248 10559660032 1267654928 13.400 100 85 166 0 1300089728 14264176 18170624
17:19:27 783 8192000000 10212742672 10596081664 1299575152 13.691 100 91 184 2 1333379760 13057280 20747328
17:20:29 15149 8192000000 10200583136 10604851200 1302681600 13.720 100 44 88 0 1320710224 7322096 10706528
17:21:46 3388 8192000000 10209451760 10621931520 1322044144 13.895 100 29 58 0 1335402144 7124944 6233056
17:22:54 9764 8192000000 10209054016 10627522560 1331427488 13.980 100 23 44 0 1342452192 5636960 5387744
17:24:04 46113 8192000000 10228848896 10650599424 1336665712 14.027 100 23 48 0 1347690112 5661472 5362928
17:26:04 6456 8192000000 10226351312 10653569024 1340946064 14.066 100 22 46 0 1350843856 4970560 4927232
17:27:11 8041 8192000000 10231060784 10653167616 1339868528 14.056 100 25 50 0 1352135056 6695840 5570688
17:28:25 16191 8192000000 10197406400 10607820800 1260426976 13.334 95 21 43 0 1270982816 5579920 4975920
17:29:41 8873 8192000000 10179638496 10594091008 1251115328 13.248 96 19 38 0 1264766784 9659712 3991744
17:30:51 6374 8192000000 10157985264 10556559360 1242076128 13.165 98 27 54 0 1254029632 6085520 5867984
17:31:58 6874 8192000000 10144468112 10545872896 1233502272 13.086 98 27 53 0 1245772288 6009344 6260672
17:33:05 8054 8192000000 10162366640 10566955008 1254984208 13.284 98 23 47 0 1266895232 5732752 6178272
17:34:16 20420 8192000000 10161078848 10575327232 1275290880 13.470 100 20 40 0 1285483616 5519536 4673200
17:35:37 6962 8192000000 10186094928 10599645184 1294676816 13.647 100 22 45 0 1305412256 5447632 5287808
17:36:46 8111 8192000000 10194037520 10612170752 1306127312 13.751 100 22 44 0 1317957600 5678112 6152176
17:37:54 14916 8192000000 10209380960 10623967232 1314167760 13.824 100 9 18 0 1320626880 4922096 1537024
17:39:12 135216 8192000000 10251405152 10672328704 1347469248 14.125 100 38 75 0 1367669344 11649312 8550784
17:42:31 4331 8192000000 10278780144 10695028736 1370174160 14.329 100 48 93 2 1393081040 12519952 10386928
17:43:41 14245 8192000000 10298037632 10719576064 1387570576 14.484 100 40 78 0 1410989456 13946096 9472784
17:45:01 6995 8192000000 10310749408 10730758144 1394587488 14.547 100 50 96 1 1426178352 20331808 11259056
17:46:18 7785 8192000000 10324166848 10749210624 1401953728 14.612 100 44 88 0 1440016672 27132672 10930272
17:47:36 10772 8192000000 10338491472 10754969600 1402437360 14.617 100 58 115 0 1445589072 29462448 13689264
17:48:52 4175 8192000000 10356763200 10773073920 1418136912 14.756 100 68 137 1 1466157136 31426112 16594112
17:50:01 10327 8192000000 10355238160 10769317888 1411068608 14.693 100 63 125 0 1460973600 34367776 15537216
17:51:15 7393 8192000000 10349166080 10765299712 1405416384 14.643 100 59 118 0 1458286608 37903216 14967008
17:52:34 7467 8192000000 10350034832 10768941056 1391089568 14.516 99 52 103 0 1452848016 47612032 14146416
17:53:48 2736 8192000000 10378170592 10791903232 1404008624 14.631 100 70 140 0 1481502480 59223600 18270256
17:54:57 6904 8192000000 10362856512 10776936448 1394709152 14.548 100 68 133 1 1471810016 59770496 17330368
17:56:12 3803 8192000000 10372870560 10797084672 1410476672 14.688 100 55 110 1 1484375328 58896096 15002560
17:57:21 8071 8192000000 10357598800 10783428608 1395708256 14.557 100 43 86 0 1462579488 55012592 11858640
17:58:33 9049 8192000000 10334451008 10760065024 1382591360 14.440 99 36 72 0 1440599264 48354352 9653552
17:59:50 4794 8192000000 10349030752 10771165184 1366514160 14.296 99 54 104 0 1438432544 57929056 13989328
18:00:57 9766 8192000000 10343481168 10774659072 1381058128 14.426 100 36 72 0 1450826640 59480992 10287520
18:02:19 16377 8192000000 10344125008 10779525120 1379098640 14.408 100 30 61 0 1449612240 61707184 8806416
18:03:44 41362 8192000000 10269874352 10719145984 1321735808 13.892 98 16 32 1 1371317984 44563840 5018336
18:06:11 55697 8192000000 10261551728 10703237120 1350301120 14.150 100 43 89 0 1371310896 10199472 10810304
18:08:08 18640 8192000000 10259761376 10695942144 1332814288 13.993 98 44 87 2 1352194656 9924656 9455712
18:09:30 62094 8192000000 10224290368 10674860032 1305659744 13.747 96 21 42 0 1320633824 10787552 4186528
18:11:36 76413 8192000000 10256044832 10707447808 1349435200 14.142 99 16 30 0 1360413872 7682496 3296176
18:13:53 30894 8192000000 10274077600 10719186944 1374013120 14.363 99 23 44 0 1389068896 10000016 5055760
18:15:28 82651 8192000000 10279714016 10744963072 1378854224 14.406 100 20 40 0 1397403792 13908528 4641040
18:18:30 32133 8192000000 10294479776 10739195904 1384906288 14.460 100 46 92 0 1401969952 6991168 10072496
18:20:17 28833 8192000000 10286043536 10738032640 1387645152 14.485 100 26 52 1 1401414928 7709296 6060480
18:21:49 71835 8192000000 10288421936 10755899392 1388258096 14.490 100 7 14 0 1399479344 9852096 1369152
18:24:18 39111 8192000000 10295751360 10754502656 1397314848 14.571 100 34 61 2 1413160816 9316976 6528992
18:26:02 78638 8192000000 10313217072 10769084416 1390505248 14.510 99 17 33 0 1405378544 10837728 4035568
18:28:24 41842 8192000000 10290204608 10753335296 1372201840 14.347 98 21 42 0 1388888880 11895088 4791952
18:30:11 14790 8192000000 10325090528 10763796480 1388638752 14.494 99 55 106 0 1413599392 13406928 11553712
18:31:26 31186 8192000000 10306746704 10755960832 1391045872 14.515 99 37 73 1 1406726032 8166320 7513840
18:33:16 57350 8192000000 10317983664 10764767232 1397080128 14.569 99 30 60 0 1417523008 13613024 6829856
18:35:18 113439 8192000000 10308358480 10760876032 1357027904 14.211 97 9 18 0 1368994240 10175328 1791008
18:38:17 112809 8192000000 10271791728 10724974592 1331450864 13.980 95 24 48 0 1346014832 9249184 5314784
18:41:11 74399 8192000000 10278228992 10743685120 1366995936 14.300 98 18 36 0 1385299440 14413296 3890208
18:44:25 65171 8192000000 10278531952 10731945984 1329625920 13.964 96 16 32 0 1348926656 15340400 3960336
18:46:41 45277 8192000000 10239535168 10682941440 1282212656 13.533 94 10 20 1 1293791856 9012848 2566352
18:48:34 43928 8192000000 10266207792 10698108928 1311953376 13.804 97 31 63 0 1328869392 9282176 7633840
18:50:22 38526 8192000000 10249114448 10693226496 1329664160 13.964 99 16 32 1 1341652112 8453680 3534272
18:52:12 65102 8192000000 10253667312 10705625088 1269162176 13.414 94 25 49 0 1290573120 16331232 5079712
18:54:17 57853 8192000000 10286233680 10721648640 1346695008 14.118 99 25 50 1 1366439808 13991632 5753168
18:56:16 41349 8192000000 10289329056 10728501248 1356311616 14.204 99 31 62 0 1374640448 11516432 6812400
18:57:58 79364 8192000000 10298397408 10738057216 1364958128 14.282 99 32 60 0 1383569056 11044496 7566432
19:00:24 68877 8192000000 10282945488 10736558080 1363231504 14.266 99 27 53 0 1379651616 9892304 6527808
19:02:33 30706 8192000000 10280608144 10732638208 1358566912 14.224 99 18 36 0 1381878784 18561040 4750832
19:04:10 56766 8192000000 10273595936 10731905024 1373772080 14.361 100 6 12 0 1387462304 12265408 1424816
19:07:02 82514 8192000000 10282580944 10743926784 1370544912 14.332 99 15 30 0 1389233408 14806320 3882176
19:10:00 76792 8192000000 10300249824 10758004736 1394246880 14.544 100 10 21 0 1408148480 11642224 2259376
19:12:22 48306 8192000000 10276083264 10723057664 1346155520 14.113 97 22 44 0 1361549104 10600832 4792752
19:14:43 41948 8192000000 10310987008 10768760832 1401977056 14.613 100 23 46 0 1418775536 11409072 5389408
19:16:27 40699 8192000000 10314669184 10755055616 1383212560 14.445 99 39 78 0 1402733792 10447648 9073584
19:18:10 33548 8192000000 10311089888 10754387968 1394379680 14.545 100 29 58 0 1410726080 10230160 6116240
19:19:52 46360 8192000000 10311659264 10768707584 1386071680 14.471 99 30 60 0 1407787920 15296448 6419792
19:21:44 120977 8192000000 10317423552 10780360704 1350405152 14.151 96 17 34 0 1371534064 17280960 3847952
19:24:45 114282 8192000000 10292434192 10763251712 1363346784 14.267 98 15 30 1 1391142624 24442336 3353504
19:28:09 65316 8192000000 10280139424 10745323520 1367150448 14.302 98 11 22 0 1378918736 9416784 2351504
19:30:29 66116 8192000000 10281110240 10739167232 1369442160 14.322 99 13 27 0 1381829408 9243936 3143312
19:32:50 41813 8192000000 10269439744 10727084032 1355323552 14.195 98 15 30 0 1364348288 5873456 3151280
19:35:24 104678 8192000000 10260697008 10726481920 1339158192 14.050 98 16 32 1 1353393744 10327248 3908304
19:38:09 20665 8192000000 9125618896 9372073984 293867936 3.463 22 2 5 0 295205360 898976 438448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-07-2022 19:39:57 to 16-07-2022 19:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 51 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-07-2022 19:40:13 to 16-07-2022 19:40:15.

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          238240
MON$OLDEST_ACTIVE               238241
MON$OLDEST_SNAPSHOT             238241
MON$NEXT_TRANSACTION            238241
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                       2019456
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.33609 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33609 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33609 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'

16.07.22 19:40:16. 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-16 19:40:18 No severe PSQL-related problems occured
16.07.22 19:40:20. End of report.