Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.03.2023 13:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14921168 kB
MemAvailable 30391668 kB
Buffers 0 kB
Cached 16744372 kB
SwapCached 3236 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2023-03-15 13:37:26
DTS_END                         2023-03-15 13:37:26.3080
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(68:17)
IP                              ::1/46720
TRN_ID                          226308
ATT_ID                          673
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: 9261.51 23727 1111381
customer order: creation 177.76 473 21331
customer order: refuse 14.76 376 1771
order to supplier: creation 47.66 1239 5719
order to supplier: removal 3.42 170028 410
invoice (draft): creation 46.45 3052 5574
invoice (draft): removal 50.43 948 6052
invoice accept: apply 43.53 26097 5223
invoice accept: cancel 45.28 1286 5434
customer reserve: creation 4065.24 247 487829
customer reserve: removal 4469.33 103 536320
realization accept: apply 86.27 285 10352
realization accept: cancel 76.73 166 9208
payment from customer: creation 61.07 182 7328
payment from customer: removal 6.13 113 736
payment to supplier: creation 50.60 196 6072
payment to supplier: removal 3.52 127 422
service: total inventory turnovers 4.06 4150 487
service: total monetary turnovers 8.21 345 985
service: aggregate perf. data 1.03 13635 123
service: refresh index statistics 0.04 251496 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-03-2023 13:38:40 to 15-03-2023 13:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10474
TEST_TIME 2 6948
TEST_TIME 3 6913
TEST_TIME 4 6212
TEST_TIME 5 6659
TEST_TIME 6 7096
TEST_TIME 7 7324
TEST_TIME 8 7232
TEST_TIME 9 7201
TEST_TIME 10 7092
TEST_TIME 11 7095
TEST_TIME 12 7178
TEST_TIME 13 7269
TEST_TIME 14 7375
TEST_TIME 15 7515
TEST_TIME 16 7620
TEST_TIME 17 7529
TEST_TIME 18 7629
TEST_TIME 19 7554
TEST_TIME 20 7751
TEST_TIME 21 7917
TEST_TIME 22 8196
TEST_TIME 23 8246
TEST_TIME 24 8196
TEST_TIME 25 8430
TEST_TIME 26 8318
TEST_TIME 27 8670
TEST_TIME 28 9016
TEST_TIME 29 9154
TEST_TIME 30 9392
TEST_TIME 31 9855
TEST_TIME 32 10109
TEST_TIME 33 10867
TEST_TIME 34 11312
TEST_TIME 35 11247
TEST_TIME 36 11131
TEST_TIME 37 11281
TEST_TIME 38 11136
TEST_TIME 39 10979
TEST_TIME 40 11026
TEST_TIME 41 11007
TEST_TIME 42 10873
TEST_TIME 43 10681
TEST_TIME 44 10505
TEST_TIME 45 10446
TEST_TIME 46 10379
TEST_TIME 47 10361
TEST_TIME 48 10275
TEST_TIME 49 10287
TEST_TIME 50 10275
TEST_TIME 51 10253
TEST_TIME 52 10121
TEST_TIME 53 10055
TEST_TIME 54 10044
TEST_TIME 55 9999
TEST_TIME 56 9988
TEST_TIME 57 9956
TEST_TIME 58 10032
TEST_TIME 59 10012
TEST_TIME 60 10065
TEST_TIME 61 10090
TEST_TIME 62 10063
TEST_TIME 63 10070
TEST_TIME 64 10072
TEST_TIME 65 10159
TEST_TIME 66 10148
TEST_TIME 67 10122
TEST_TIME 68 10170
TEST_TIME 69 10094
TEST_TIME 70 10040
TEST_TIME 71 9987
TEST_TIME 72 9935
TEST_TIME 73 9872
TEST_TIME 74 9822
TEST_TIME 75 9797
TEST_TIME 76 9796
TEST_TIME 77 9752
TEST_TIME 78 9685
TEST_TIME 79 9648
TEST_TIME 80 9590
TEST_TIME 81 9673
TEST_TIME 82 9621
TEST_TIME 83 9584
TEST_TIME 84 9599
TEST_TIME 85 9685
TEST_TIME 86 9732
TEST_TIME 87 9697
TEST_TIME 88 9713
TEST_TIME 89 9731
TEST_TIME 90 9712
TEST_TIME 91 9682
TEST_TIME 92 9604
TEST_TIME 93 9589
TEST_TIME 94 9551
TEST_TIME 95 9475
TEST_TIME 96 9453
TEST_TIME 97 9421
TEST_TIME 98 9395
TEST_TIME 99 9387
TEST_TIME 100 9383
TEST_TIME 101 9362
TEST_TIME 102 9391
TEST_TIME 103 9388
TEST_TIME 104 9469
TEST_TIME 105 9521
TEST_TIME 106 9524
TEST_TIME 107 9566
TEST_TIME 108 9579
TEST_TIME 109 9526
TEST_TIME 110 9479
TEST_TIME 111 9444
TEST_TIME 112 9412
TEST_TIME 113 9421
TEST_TIME 114 9392
TEST_TIME 115 9357
TEST_TIME 116 9328
TEST_TIME 117 9304
TEST_TIME 118 9303
TEST_TIME 119 9312
TEST_TIME 120 9281

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-03-2023 13:38:44 to 15-03-2023 13:38:47.

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 1558705 1558705 0 0.00 0 14352 13 0 0
doc_list_biud 1558701 1558701 0 0.00 0 30866 41 0 0
sp_add_invoice_to_stock 5305 5223 82 1.54 63 208851 26098 0 82
sp_cancel_adding_invoice 5434 5434 0 0.00 5 100112 1286 0 0
sp_cancel_client_order 1771 1771 0 0.00 6 8266 376 0 0
sp_cancel_customer_reserve 536320 536320 0 0.00 1 19475 103 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 4 2987 114 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 4 11917 127 0 0
sp_cancel_supplier_invoice 6060 6052 8 0.13 2 19936 948 0 8
sp_cancel_supplier_order 410 410 0 0.00 10 1492059 170028 0 0
sp_cancel_write_off 9208 9208 0 0.00 2 6718 167 0 0
sp_client_order 21331 21331 0 0.00 6 18958 473 0 0
sp_customer_reserve 487829 487829 0 0.00 0 146849 247 0 0
sp_fill_shopping_cart 32618 32618 0 0.00 1 22928 79 0 0
sp_fill_shopping_cart_clo_res 487810 487810 0 0.00 0 17451 17 0 0
sp_get_clo_for_invoice 5182 5182 0 0.00 0 27009 241 0 0
sp_kill_cost_storno 15800 15800 0 0.00 0 6887 74 0 0
sp_kill_qstorno_ret_qs2qd 1088310 1088310 0 0.00 0 30425 44 0 0
sp_kill_qty_storno 574728 574728 0 0.00 0 30859 101 0 0
sp_lock_selected_doc 21430 21430 0 0.00 0 11165 9 0 0
sp_make_cost_storno 28933 28933 0 0.00 0 14297 82 0 0
sp_multiply_rows_for_pdistr 28933 28933 0 0.00 0 12370 37 0 0
sp_multiply_rows_for_qdistr 21331 21331 0 0.00 0 14751 193 0 0
sp_pay_from_customer 7328 7328 0 0.00 5 17999 182 0 0
sp_pay_to_supplier 6072 6072 0 0.00 6 19955 197 0 0
sp_payment_common 13400 13400 0 0.00 2 9763 47 0 0
sp_qd_handle_on_cancel_clo 1771 1771 0 0.00 1 8254 302 0 0
sp_qd_handle_on_invoice_upd_sts 10615 10615 0 0.00 0 24998 517 0 0
sp_qd_handle_on_reserve_upd_sts 10352 10352 0 0.00 0 30852 63 0 0
sp_reserve_write_off 10352 10352 0 0.00 7 31065 285 0 0
sp_supplier_invoice 5574 5574 0 0.00 11 87318 3053 0 0
sp_supplier_order 5719 5719 0 0.00 7 23570 1239 0 0
srv_aggregate_perf_data 123 123 0 0.00 2247 129134 13635 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 772 129953 35846 0 0
srv_find_qd_qs_mism 1059447 1059447 0 0.00 0 13169 10 0 0
srv_make_invnt_saldo 487 487 0 0.00 70 64065 4150 0 0
srv_make_money_saldo 985 985 0 0.00 1 8754 345 0 0
srv_recalc_idx_stat 5 5 0 0.00 45074 486073 251496 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5223 5223 0 0.00 0 33443 957 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 7360 441 0 0
t$perf-norm:sp_cancel_client_order 1771 1771 0 0.00 0 3107 27 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 3326 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 2697 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 879 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 3775 37 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 79363 7022 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 19103 38 0 0
t$perf-norm:sp_client_order 21331 21331 0 0.00 0 7966 37 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 931 16 0 0
t$perf-norm:sp_pay_from_customer 7328 7328 0 0.00 0 16119 26 0 0
t$perf-norm:sp_pay_to_supplier 6072 6072 0 0.00 0 6480 21 0 0
t$perf-norm:sp_reserve_write_off 10352 10352 0 0.00 0 5806 21 0 0
t$perf-norm:sp_supplier_invoice 5574 5574 0 0.00 0 4955 35 0 0
t$perf-norm:sp_supplier_order 5719 5719 0 0.00 0 4896 32 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 674 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 285 7 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 2749 25 0 0
t$perf-norm:srv_make_money_saldo 985 985 0 0.00 0 478 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 8 197 63 0 0
v_add_invoice_to_stock 10524 10360 164 1.55 0 2626 23 0 164
v_all_customers 38528 38528 0 0.00 0 138 4 0 0
v_all_suppliers 31638 31638 0 0.00 0 234 4 0 0
v_all_wares 42662 42662 0 0.00 0 150 4 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 3000 24 0 0
v_cancel_client_order 3542 3542 0 0.00 0 3552 10 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 1507 11 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 1388 13 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 400 11 0 0
v_cancel_supplier_invoice 840 824 16 1.90 0 5532 32 0 16
v_cancel_supplier_order 820 820 0 0.00 0 1297 15 0 0
v_cancel_write_off 2084 2084 0 0.00 0 3524 29 0 0
v_max_id_clo_ord 5719 5719 0 0.00 0 1116 5 0 0
v_max_id_clo_res 1055 1055 0 0.00 0 1515 15 0 0
v_max_id_ord_sup 5568 5568 0 0.00 0 3702 6 0 0
v_max_non_paid_invoice 1540 1540 0 0.00 0 165 8 0 0
v_max_non_paid_realizn 3967 3967 0 0.00 0 360 7 0 0
v_min_id_clo_ord 5719 5719 0 0.00 0 1199 8 0 0
v_min_id_clo_res 1055 1055 0 0.00 0 1190 10 0 0
v_min_id_ord_sup 5568 5568 0 0.00 0 7068 12 0 0
v_min_non_paid_invoice 6072 6072 0 0.00 0 9120 18 0 0
v_min_non_paid_realizn 7328 7328 0 0.00 0 6596 16 0 0
v_our_firm 10856 10856 0 0.00 0 85 5 0 0
v_reserve_write_off 20704 20704 0 0.00 0 3157 10 0 0
x_lock_depdocs_on_canc_invoice 11074 11074 0 0.00 0 13980 138 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 11830 398 0 0
x_make_qstorno_1000_1200 5719 5719 0 0.00 3 23555 1071 0 0
x_make_qstorno_1200_2000 5574 5574 0 0.00 6 87286 2847 0 0
x_make_qstorno_2100_3300 487829 487829 0 0.00 0 146848 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-03-2023 13:38:48 to 15-03-2023 13:38:52.

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
11:07:30 927 8192000000 8929326352 8986042368 12585904 0.153 5 2 4 0 12998640 107552 305184
11:08:32 29044 8192000000 9725315152 10026299392 801405584 8.911 100 84 168 0 830318016 7674544 21237888
11:10:09 14628 8192000000 9766864288 10076176384 842289632 9.323 100 89 178 0 875701200 12643632 20767936
11:11:32 13614 8192000000 9806880816 10129252352 880205344 9.702 100 87 173 0 919549952 19982992 19361616
11:12:50 9025 8192000000 9869581296 10204319744 920018480 10.096 100 82 161 0 976107408 38850432 17238496
11:14:02 5898 8192000000 9936296768 10280398848 958178944 10.471 100 81 156 1 1045459024 70395392 16884688
11:15:13 2161 8192000000 9974073712 10324914176 994813776 10.828 100 92 179 1 1087962960 74939648 18209536
11:16:17 1019 8192000000 9993913136 10358927360 1019455136 11.067 100 87 169 1 1109475824 73395024 16625664
11:17:20 892 8192000000 10013606384 10383613952 1037968400 11.245 100 96 174 0 1136102800 80138192 17996208
11:18:23 1215 8192000000 10039302640 10413563904 1054300976 11.402 100 97 188 1 1164738192 91429552 19007664
11:19:26 1031 8192000000 10040075952 10417377280 1066301280 11.517 100 94 178 1 1163571504 79038816 18231408
11:20:28 877 8192000000 10048642288 10428026880 1071967232 11.571 100 95 183 1 1176169920 85482112 18720576
11:21:30 914 8192000000 10047008288 10433863680 1075794464 11.607 100 92 181 1 1168955344 73823888 19336992
11:22:32 1219 8192000000 10056854576 10444554240 1078500320 11.633 100 97 180 2 1177913296 79567584 19845392
11:23:34 1155 8192000000 10053439936 10440019968 1085604480 11.701 100 96 189 1 1168730928 64547840 18578608
11:24:37 1039 8192000000 10047052288 10436145152 1090539712 11.748 100 92 169 2 1168501136 61404464 16556960
11:25:39 1147 8192000000 10061566080 10454773760 1091440000 11.756 100 84 164 2 1188986784 80347712 17199072
11:26:41 4431 8192000000 10049555472 10440355840 1094800432 11.788 100 69 128 3 1172397232 65269552 12327248
11:27:46 1086 8192000000 10059523008 10448785408 1094275136 11.783 100 95 179 1 1178959712 65906736 18777840
11:28:48 1058 8192000000 10082665088 10472919040 1094247296 11.783 100 97 189 3 1203812448 90009840 19555312
11:29:50 1054 8192000000 10018542480 10406457344 1075162016 11.601 100 89 174 2 1141318592 48336464 17820112
11:30:53 1131 8192000000 10004763840 10386997248 1061390720 11.470 100 95 185 2 1132684560 51469312 19824528
11:31:55 1059 8192000000 9965781472 10339721216 1024940736 11.120 99 84 158 1 1087300400 46441440 15918224
11:32:57 988 8192000000 9997767664 10376294400 1043984896 11.303 100 91 171 4 1115968064 53140064 18843104
11:33:59 4697 8192000000 9986905168 10374668288 1040989760 11.274 99 60 114 0 1114279008 61926464 11362784
11:35:05 1109 8192000000 9997129776 10370957312 1040936800 11.274 100 96 181 3 1120556656 59511728 20108128
11:36:06 3152 8192000000 10006253808 10388660224 1047858224 11.340 100 78 142 1 1127391072 63807248 15725600
11:37:10 1088 8192000000 9985824992 10367844352 1061318912 11.469 100 77 144 4 1106554064 30947600 14287552
11:38:16 1511 8192000000 10022223696 10401153024 1071528384 11.567 100 94 174 0 1147906832 57640176 18738272
11:39:25 31614 8192000000 10027260864 10418372608 1059634736 11.453 98 63 124 0 1153020752 80564864 12821152
11:41:06 823 8192000000 10066460656 10448240640 1096749520 11.807 100 94 181 0 1186195536 69462992 19983024
11:42:10 1303 8192000000 10117945872 10509336576 1110241552 11.935 100 84 156 2 1232808640 107186768 15380320
11:43:14 995 8192000000 10138310368 10530664448 1131319456 12.134 100 96 184 2 1261523056 110661168 19542432
11:44:17 1183 8192000000 10158854848 10548350976 1131434208 12.135 100 92 173 0 1276506016 125992336 19079472
11:45:22 1141 8192000000 10204286000 10597908480 1146134400 12.273 100 90 169 2 1318087856 153992880 17960576
11:46:26 7735 8192000000 10230716608 10641940480 1151449824 12.323 100 68 131 2 1342706928 176621120 14635984
11:47:38 3915 8192000000 10234892112 10648653824 1160935328 12.412 100 78 148 2 1348014768 170900864 16178576
11:48:49 4179 8192000000 10279150144 10679439360 1167613456 12.475 100 84 160 1 1393251536 206405904 19232176
11:49:56 1060 8192000000 10284733968 10706169856 1178630784 12.577 100 83 157 0 1400294192 203487136 18176272
11:51:01 2087 8192000000 10331659968 10743046144 1182898720 12.617 100 78 147 1 1447838272 247982080 16957472
11:52:06 1166 8192000000 10333773104 10755514368 1188423216 12.669 100 90 170 0 1437788864 229970128 19395520
11:53:09 1041 8192000000 10385923280 10801811456 1190471232 12.688 100 94 177 1 1490379696 280111104 19797360
11:54:15 2027 8192000000 10400842944 10824458240 1203479680 12.809 100 77 150 0 1510396896 288600832 18316384
11:55:23 2338 8192000000 10430880288 10860064768 1206195488 12.834 100 81 162 1 1541197296 315239536 19762272
11:56:28 1283 8192000000 10463736064 10888118272 1202605872 12.801 100 84 166 0 1572986320 348574416 21806032
11:57:34 943 8192000000 10472054192 10900570112 1201265296 12.788 100 96 183 0 1576436128 353300208 21870624
11:58:39 764 8192000000 10495518928 10940366848 1193253088 12.714 100 99 193 0 1603663456 388159824 22250544
11:59:42 1089 8192000000 10521905136 10961395712 1185647072 12.643 99 96 185 0 1622378992 414964144 21767776
12:00:49 888 8192000000 10528059312 10946973696 1178681248 12.578 100 83 162 1 1631327456 432738736 19907472
12:01:53 847 8192000000 10572366768 10999881728 1183900048 12.627 100 96 186 1 1674401840 467739392 22762400
12:02:58 1298 8192000000 10584308672 10997608448 1192025936 12.702 100 87 169 0 1687983456 475445216 20512304
12:04:03 13354 8192000000 10549241808 11072208896 1179008608 12.581 99 64 128 1 1663992752 468126880 16857264
12:05:17 4710 8192000000 10567295888 11050762240 1193485344 12.716 100 65 130 0 1666863072 457822144 15555584
12:06:23 12075 8192000000 10580746752 11035443200 1170761584 12.504 100 38 76 0 1675958160 494541168 10655408
12:07:37 9279 8192000000 10509983984 10979344384 1176655008 12.559 100 45 85 2 1621718096 433872192 11190896
12:08:49 34536 8192000000 10380556880 10900680704 1163352704 12.435 100 25 50 0 1511536416 341438304 6745408
12:10:33 42466 8192000000 10146523184 10658988032 1132915328 12.149 98 15 30 1 1297078688 160600288 3563072
12:12:23 48368 8192000000 10106907664 10550140928 1107381280 11.908 96 12 21 0 1142694560 33357888 1955392
12:14:12 63893 8192000000 10102970064 10583003136 1139667744 12.212 99 13 26 0 1209328528 66805584 2855200
12:16:16 22015 8192000000 10117193056 10543681536 1143315696 12.247 99 50 94 0 1188729952 35501088 9913168
12:17:42 78807 8192000000 10096666544 10574913536 1116180416 11.991 97 19 38 0 1195664048 75396016 4087616
12:20:02 95688 8192000000 10082548656 10515857408 1111170624 11.943 98 30 60 1 1162218944 43502624 7545696
12:22:38 76476 8192000000 10062682880 10503028736 1123167040 12.057 99 46 81 1 1156258864 25034992 8056832
12:24:59 21731 8192000000 10089636656 10520363008 1122603264 12.052 99 41 80 0 1186326256 55718192 8004800
12:26:23 11874 8192000000 10175238608 10593972224 1143165136 12.245 100 76 151 2 1270852800 111399760 16287904
12:27:39 40301 8192000000 10096353648 10567266304 1108261776 11.916 97 23 42 0 1184870560 72600048 4008736
12:29:20 106935 8192000000 10152208320 10617864192 1143412432 12.248 100 13 25 1 1243616800 97166096 3038272
12:32:08 42702 8192000000 10108867728 10553008128 1128346528 12.106 99 20 34 1 1205594656 72800048 4448080
12:33:51 48494 8192000000 10108659296 10577907712 1075704512 11.607 94 13 26 0 1140404736 61892448 2807776
12:35:42 64094 8192000000 10112705408 10547896320 1114700624 11.977 98 30 60 0 1166958912 46239952 6018336
12:37:54 37378 8192000000 10119990064 10590027776 1144051776 12.254 100 37 71 0 1204903232 53038000 7813456
12:39:38 30921 8192000000 10120008480 10561736704 1101804128 11.855 96 35 68 0 1182269920 74200560 6265232
12:41:09 97061 8192000000 10080293520 10571579392 1076335968 11.613 94 29 58 0 1122047392 40141072 5570352
12:43:59 62478 8192000000 10110209920 10558394368 1106510416 11.899 97 28 56 0 1173569920 61003584 6055920
12:46:06 73980 8192000000 10089162320 10556096512 1102643712 11.863 97 37 69 1 1163413456 53357456 7412288
12:49:05 38754 8192000000 10080064560 10521665536 1130884112 12.130 100 45 86 1 1168231232 28849808 8497312
12:51:02 39958 8192000000 10144580608 10613727232 1133175920 12.151 99 24 48 0 1227187328 89019472 4991936
12:52:43 63558 8192000000 10137507840 10641162240 1137687200 12.194 100 21 41 0 1243509104 100937728 4884176
12:54:47 72707 8192000000 10094217840 10563854336 1133587360 12.155 100 16 32 0 1200410528 62938016 3885152
12:57:14 83249 8192000000 10130950976 10604392448 1107920752 11.913 97 19 39 0 1190270480 77720704 4629024
12:59:38 44358 8192000000 10123018608 10568306688 1112793440 11.959 97 27 52 1 1179867792 60786528 6287824
13:01:22 56183 8192000000 10123787808 10613587968 1133471184 12.154 99 23 43 0 1249703136 110959296 5272656
13:03:20 47381 8192000000 10057988304 10485706752 1101522704 11.852 98 39 74 1 1163254656 54397760 7334192
13:05:08 17322 8192000000 10124849920 10565885952 1124429168 12.069 100 43 86 0 1217983504 84802240 8752096
13:06:26 38861 8192000000 10073946624 10520928256 1112165136 11.953 99 35 69 1 1163623120 45281648 6176336
13:08:07 105576 8192000000 10081302128 10528997376 1105116912 11.886 98 33 66 1 1186773600 75256624 6400064
13:11:21 49802 8192000000 10043372864 10514128896 1112962752 11.960 100 9 18 0 1155127472 40474608 1690112
13:13:11 33027 8192000000 10030268928 10488365056 1088745616 11.731 98 9 15 0 1137005904 46921344 1338944
13:14:44 43187 8192000000 10091948272 10527973376 1120551456 12.032 99 38 74 0 1173808880 44443440 8813984
13:16:30 18898 8192000000 10123445872 10573295616 1132055920 12.141 100 39 75 0 1227735904 87850816 7829168
13:17:52 36939 8192000000 10122059216 10577207296 1122161312 12.047 99 35 70 0 1206675584 77016800 7497472
13:19:35 32608 8192000000 10164030208 10627874816 1139041184 12.207 99 47 92 1 1256721104 107771072 9908848
13:21:16 88800 8192000000 10138846016 10629844992 1132277264 12.143 99 14 26 0 1230736352 95804368 2654720
13:23:47 40358 8192000000 10129853600 10602979328 1145255040 12.265 100 33 66 0 1226144800 74048816 6840944
13:25:28 80278 8192000000 10072925040 10517483520 1100767120 11.845 96 29 59 1 1153857184 45563696 7526368
13:27:49 129953 8192000000 10106895920 10567753728 1111643056 11.948 97 22 44 0 1162032416 46076144 4313216
13:30:59 37485 8192000000 10069521488 10556776448 1132061168 12.141 99 12 24 0 1171079872 36491232 2527472
13:32:37 73600 8192000000 10108592960 10568663040 1128762592 12.110 98 17 32 0 1183338688 51069792 3506304
13:34:51 53817 8192000000 10113423984 10608390144 1128559600 12.108 99 6 12 0 1195345664 65581664 1204400
13:36:50 43068 8192000000 9204383472 9507065856 351005792 4.108 31 3 6 0 370498880 18933248 559840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 15-03-2023 13:38:54 to 15-03-2023 13:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-03-2023 13:39:12 to 15-03-2023 13:39:14.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          228581
MON$OLDEST_ACTIVE               228582
MON$OLDEST_SNAPSHOT             228582
MON$NEXT_TRANSACTION            228582
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-30 07:30:08.6030 GMT
MON$PAGES                       2071392
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2909 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2909 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2909 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2909 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:36: 
:37: mscs-test-161.budzdorov.ru    Wed Mar 15 13:37:36 2023
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
--- end of diff output ---

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

15.03.23 13:39:15. There are 3 logs in /var/tmp/logs-oltp40 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
2023-03-15 13:39:18 No severe PSQL-related problems occured
15.03.23 13:39:19. End of report.