Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.02.2023 04: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 14905052 kB
MemAvailable 30367388 kB
Buffers 20 kB
Cached 16708576 kB
SwapCached 3360 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-02-11 04:37:38
DTS_END                         2023-02-11 04:37:27.3490
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55848
TRN_ID                          220061
ATT_ID                          259
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: 9272.76 21149 1112731
customer order: creation 174.33 487 20920
customer order: refuse 14.18 399 1701
order to supplier: creation 43.81 1395 5257
order to supplier: removal 3.48 170747 418
invoice (draft): creation 44.12 3117 5294
invoice (draft): removal 53.58 991 6429
invoice accept: apply 41.84 25518 5021
invoice accept: cancel 48.13 1135 5776
customer reserve: creation 3885.89 243 466307
customer reserve: removal 4670.61 99 560473
realization accept: apply 83.41 272 10009
realization accept: cancel 79.65 175 9558
payment from customer: creation 59.43 179 7132
payment from customer: removal 5.68 110 681
payment to supplier: creation 47.65 185 5718
payment to supplier: removal 3.73 122 447
service: total inventory turnovers 4.40 3454 528
service: total monetary turnovers 7.91 474 949
service: aggregate perf. data 0.90 16245 108
service: refresh index statistics 0.04 197639 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-02-2023 04:38:29 to 11-02-2023 04:38:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6148
TEST_TIME 2 6217
TEST_TIME 3 6567
TEST_TIME 4 6292
TEST_TIME 5 6542
TEST_TIME 6 6736
TEST_TIME 7 6447
TEST_TIME 8 6665
TEST_TIME 9 6824
TEST_TIME 10 6613
TEST_TIME 11 6930
TEST_TIME 12 6879
TEST_TIME 13 6950
TEST_TIME 14 7098
TEST_TIME 15 7164
TEST_TIME 16 7489
TEST_TIME 17 7634
TEST_TIME 18 7733
TEST_TIME 19 7723
TEST_TIME 20 7911
TEST_TIME 21 8108
TEST_TIME 22 8154
TEST_TIME 23 8078
TEST_TIME 24 8013
TEST_TIME 25 8375
TEST_TIME 26 8602
TEST_TIME 27 8919
TEST_TIME 28 9202
TEST_TIME 29 9606
TEST_TIME 30 9857
TEST_TIME 31 9969
TEST_TIME 32 10606
TEST_TIME 33 10787
TEST_TIME 34 11436
TEST_TIME 35 11407
TEST_TIME 36 11372
TEST_TIME 37 11223
TEST_TIME 38 11204
TEST_TIME 39 11063
TEST_TIME 40 10952
TEST_TIME 41 10695
TEST_TIME 42 10564
TEST_TIME 43 10442
TEST_TIME 44 10448
TEST_TIME 45 10363
TEST_TIME 46 10274
TEST_TIME 47 10315
TEST_TIME 48 10380
TEST_TIME 49 10359
TEST_TIME 50 10336
TEST_TIME 51 10249
TEST_TIME 52 10124
TEST_TIME 53 10078
TEST_TIME 54 10205
TEST_TIME 55 10152
TEST_TIME 56 10064
TEST_TIME 57 10033
TEST_TIME 58 10138
TEST_TIME 59 10062
TEST_TIME 60 9976
TEST_TIME 61 10041
TEST_TIME 62 9978
TEST_TIME 63 10078
TEST_TIME 64 10022
TEST_TIME 65 10047
TEST_TIME 66 10099
TEST_TIME 67 10125
TEST_TIME 68 10150
TEST_TIME 69 10106
TEST_TIME 70 9991
TEST_TIME 71 9912
TEST_TIME 72 9841
TEST_TIME 73 9837
TEST_TIME 74 9791
TEST_TIME 75 9733
TEST_TIME 76 9671
TEST_TIME 77 9614
TEST_TIME 78 9581
TEST_TIME 79 9577
TEST_TIME 80 9639
TEST_TIME 81 9640
TEST_TIME 82 9595
TEST_TIME 83 9630
TEST_TIME 84 9633
TEST_TIME 85 9577
TEST_TIME 86 9631
TEST_TIME 87 9763
TEST_TIME 88 9799
TEST_TIME 89 9831
TEST_TIME 90 9779
TEST_TIME 91 9681
TEST_TIME 92 9674
TEST_TIME 93 9667
TEST_TIME 94 9635
TEST_TIME 95 9566
TEST_TIME 96 9554
TEST_TIME 97 9510
TEST_TIME 98 9495
TEST_TIME 99 9482
TEST_TIME 100 9454
TEST_TIME 101 9505
TEST_TIME 102 9491
TEST_TIME 103 9436
TEST_TIME 104 9422
TEST_TIME 105 9442
TEST_TIME 106 9468
TEST_TIME 107 9431
TEST_TIME 108 9480
TEST_TIME 109 9483
TEST_TIME 110 9534
TEST_TIME 111 9513
TEST_TIME 112 9470
TEST_TIME 113 9437
TEST_TIME 114 9384
TEST_TIME 115 9371
TEST_TIME 116 9363
TEST_TIME 117 9349
TEST_TIME 118 9349
TEST_TIME 119 9336
TEST_TIME 120 9291

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-02-2023 04:38:33 to 11-02-2023 04:38:36.

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 1541258 1541258 0 0.00 0 30968 13 0 0
doc_list_biud 1541249 1541249 0 0.00 0 71533 41 0 0
sp_add_invoice_to_stock 5126 5021 105 2.04 80 229731 25519 0 105
sp_cancel_adding_invoice 5776 5776 0 0.00 4 80923 1135 0 0
sp_cancel_client_order 1701 1701 0 0.00 6 14773 400 0 0
sp_cancel_customer_reserve 560473 560473 0 0.00 1 71536 99 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 4 4452 110 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 4 3198 122 0 0
sp_cancel_supplier_invoice 6443 6429 14 0.21 3 29699 991 0 14
sp_cancel_supplier_order 418 418 0 0.00 18 1414445 170747 0 0
sp_cancel_write_off 9558 9558 0 0.00 1 10130 175 0 0
sp_client_order 20920 20920 0 0.00 5 28207 487 0 0
sp_customer_reserve 466307 466307 0 0.00 0 104395 243 0 0
sp_fill_shopping_cart 31460 31460 0 0.00 1 13372 76 0 0
sp_fill_shopping_cart_clo_res 466271 466271 0 0.00 0 19687 19 0 0
sp_get_clo_for_invoice 4979 4979 0 0.00 0 17531 271 0 0
sp_kill_cost_storno 16462 16462 0 0.00 0 12070 72 0 0
sp_kill_qstorno_ret_qs2qd 1137351 1137351 0 0.00 0 71528 43 0 0
sp_kill_qty_storno 599338 599338 0 0.00 0 71533 99 0 0
sp_lock_selected_doc 20736 20736 0 0.00 0 9938 8 0 0
sp_make_cost_storno 27837 27837 0 0.00 0 23632 81 0 0
sp_multiply_rows_for_pdistr 27834 27834 0 0.00 0 8540 38 0 0
sp_multiply_rows_for_qdistr 20920 20920 0 0.00 0 14577 194 0 0
sp_pay_from_customer 7132 7132 0 0.00 5 15772 180 0 0
sp_pay_to_supplier 5718 5718 0 0.00 7 30543 186 0 0
sp_payment_common 12850 12850 0 0.00 2 8425 46 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 0 14765 335 0 0
sp_qd_handle_on_invoice_upd_sts 10752 10752 0 0.00 0 20728 481 0 0
sp_qd_handle_on_reserve_upd_sts 10007 10007 0 0.00 0 13442 60 0 0
sp_reserve_write_off 10009 10009 0 0.00 8 14138 272 0 0
sp_supplier_invoice 5294 5294 0 0.00 9 83619 3118 0 0
sp_supplier_order 5257 5257 0 0.00 7 36809 1395 0 0
srv_aggregate_perf_data 108 108 0 0.00 2166 107940 16246 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 775 136430 37667 0 0
srv_find_qd_qs_mism 1063326 1063326 0 0.00 0 26381 10 0 0
srv_make_invnt_saldo 528 528 0 0.00 35 68517 3454 0 0
srv_make_money_saldo 949 949 0 0.00 1 38230 474 0 0
srv_recalc_idx_stat 5 5 0 0.00 56468 351469 197639 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5023 5023 0 0.00 1 14572 946 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 1 6983 467 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 2445 26 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 844 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 420 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 332 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 2508 37 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 90132 6907 0 0
t$perf-norm:sp_cancel_write_off 1034 1034 0 0.00 0 4120 25 0 0
t$perf-norm:sp_client_order 20920 20920 0 0.00 0 11824 39 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 940 13 0 0
t$perf-norm:sp_pay_from_customer 7132 7132 0 0.00 0 11806 24 0 0
t$perf-norm:sp_pay_to_supplier 5718 5718 0 0.00 0 9442 23 0 0
t$perf-norm:sp_reserve_write_off 10009 10009 0 0.00 0 7451 25 0 0
t$perf-norm:sp_supplier_invoice 5294 5294 0 0.00 0 10042 32 0 0
t$perf-norm:sp_supplier_order 5257 5257 0 0.00 0 5435 33 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 436 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 1094 25 0 0
t$perf-norm:srv_make_invnt_saldo 528 528 0 0.00 0 2198 14 0 0
t$perf-norm:srv_make_money_saldo 949 949 0 0.00 0 3327 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 212 88 0 0
v_add_invoice_to_stock 10156 9946 210 2.06 0 5353 23 0 210
v_all_customers 38538 38538 0 0.00 0 165 4 0 0
v_all_suppliers 29552 29552 0 0.00 0 168 4 0 0
v_all_wares 41839 41839 0 0.00 0 184 4 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 3562 17 0 0
v_cancel_client_order 3402 3402 0 0.00 0 516 8 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 1343 11 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 5317 18 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 3132 14 0 0
v_cancel_supplier_invoice 836 808 28 3.34 0 1427 24 0 28
v_cancel_supplier_order 836 836 0 0.00 0 391 10 0 0
v_cancel_write_off 2068 2068 0 0.00 0 3006 26 0 0
v_max_id_clo_ord 5254 5254 0 0.00 0 8135 6 0 0
v_max_id_clo_res 989 989 0 0.00 0 3854 24 0 0
v_max_id_ord_sup 5286 5286 0 0.00 0 4162 8 0 0
v_max_non_paid_invoice 1482 1482 0 0.00 0 1356 8 0 0
v_max_non_paid_realizn 3607 3607 0 0.00 0 857 8 0 0
v_min_id_clo_ord 5254 5254 0 0.00 0 1064 6 0 0
v_min_id_clo_res 989 989 0 0.00 0 5319 19 0 0
v_min_id_ord_sup 5286 5286 0 0.00 0 2917 8 0 0
v_min_non_paid_invoice 5718 5718 0 0.00 0 6476 16 0 0
v_min_non_paid_realizn 7132 7132 0 0.00 0 7631 20 0 0
v_our_firm 10350 10350 0 0.00 0 59 5 0 0
v_reserve_write_off 20014 20014 0 0.00 0 5347 11 0 0
x_lock_depdocs_on_canc_invoice 11801 11801 0 0.00 0 16928 116 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 10780 365 0 0
x_make_qstorno_1000_1200 5257 5257 0 0.00 3 36797 1242 0 0
x_make_qstorno_1200_2000 5294 5294 0 0.00 4 83011 2906 0 0
x_make_qstorno_2100_3300 466307 466307 0 0.00 0 104393 212 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 11-02-2023 04:38:37 to 11-02-2023 04:38: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
02:07:31 2 8192000000 8798594704 8809799680 4494752 0.054 2 1 2 0 4594176 63648 35776
02:08:31 6108 8192000000 9709816144 10006650880 792458128 8.820 100 89 178 0 821538368 7345840 21734400
02:09:38 23930 8192000000 9760342800 10070196224 836830400 9.268 100 91 182 1 870458512 11396752 22231360
02:11:03 14816 8192000000 9788935504 10103492608 858514864 9.485 100 87 174 0 895881792 16601712 20765216
02:12:26 10292 8192000000 9846518496 10178195456 899838016 9.897 100 81 162 0 954163056 36022320 18302720
02:13:40 4302 8192000000 9904261392 10244386816 939791392 10.291 100 84 168 1 1013872128 56311280 17769456
02:14:47 3300 8192000000 9962408256 10314108928 978211248 10.667 100 84 164 1 1071055840 76046112 16798480
02:15:55 1721 8192000000 9986376368 10343530496 1004565296 10.923 100 88 170 0 1105874384 84312688 16996400
02:16:59 924 8192000000 10014595360 10379292672 1039390368 11.259 100 94 175 0 1133294592 75642944 18261280
02:18:02 1161 8192000000 10016983056 10392330240 1056085312 11.419 100 97 190 2 1139873120 65436704 18351104
02:19:05 960 8192000000 10045627536 10423357440 1067023808 11.524 100 94 180 1 1168500912 82421904 19055200
02:20:08 1485 8192000000 10026031952 10402566144 1069254016 11.545 100 83 152 1 1153734624 69836176 14644432
02:21:11 995 8192000000 10040598112 10425233408 1076844144 11.617 100 83 160 0 1162058160 68700544 16513472
02:22:14 1595 8192000000 10041555120 10426998784 1077235328 11.621 100 76 141 0 1170069248 79305504 13528416
02:23:17 11183 8192000000 10025622544 10423132160 1084052384 11.686 100 65 128 3 1150354192 53622688 12679120
02:24:30 1028 8192000000 10039939456 10430521344 1086066032 11.705 100 80 147 3 1163275584 62437120 14772432
02:25:32 1132 8192000000 10035884704 10425839616 1089216144 11.735 100 94 173 1 1161762112 52814144 19731824
02:26:34 1266 8192000000 10081093488 10469953536 1093816736 11.779 100 96 192 0 1200826272 87840256 19169280
02:27:36 1044 8192000000 10058184608 10445955072 1092320160 11.765 100 91 174 1 1179908320 69422224 18165936
02:28:40 1033 8192000000 10069066496 10458238976 1093328832 11.774 100 89 165 0 1192194512 82085280 16780400
02:29:43 2729 8192000000 10035177424 10427547648 1085764384 11.702 100 82 148 7 1161782800 60853648 15164768
02:30:46 1001 8192000000 10020135312 10406957056 1077766864 11.626 100 89 156 1 1138910160 45184224 15959072
02:31:48 7602 8192000000 9992748128 10371461120 1044578768 11.309 99 71 121 0 1114221392 57098032 12544592
02:32:56 1138 8192000000 10013759216 10391085056 1057332544 11.431 100 89 167 3 1137204624 62020480 17851600
02:33:58 876 8192000000 9988825584 10358042624 1027676432 11.146 99 94 182 5 1111918144 65368048 18873664
02:35:00 1185 8192000000 9958285680 10336378880 1026219408 11.132 100 71 125 3 1081767424 43912784 11635232
02:36:03 1022 8192000000 10002955184 10382995456 1040291824 11.267 100 82 161 1 1122880992 65869952 16719216
02:37:05 1402 8192000000 9993424032 10368798720 1041141760 11.276 99 92 159 9 1116599840 57934240 17523840
02:38:14 12178 8192000000 10001675168 10382811136 1057025328 11.428 100 75 128 1 1119910672 50912880 11972464
02:39:31 1559 8192000000 10054862480 10445049856 1081881472 11.665 100 95 185 4 1172564768 72166000 18517296
02:40:37 4675 8192000000 10068778736 10461032448 1094394800 11.784 100 77 151 0 1197885968 88182720 15308448
02:41:45 6353 8192000000 10113926608 10504540160 1108112528 11.915 100 76 146 1 1227910896 104739520 15058848
02:42:59 2799 8192000000 10130493472 10534305792 1118093952 12.009 100 78 152 0 1244319680 110137920 16087808
02:44:09 7109 8192000000 10162880304 10564382720 1130561488 12.127 100 57 114 1 1272542880 129656864 12324528
02:45:24 1602 8192000000 10202965584 10612076544 1152346320 12.332 100 87 165 0 1323487360 153492992 17648048
02:46:32 1027 8192000000 10264373296 10665340928 1164772592 12.448 100 94 183 0 1380309456 194241136 21295728
02:47:37 2068 8192000000 10308084944 10706305024 1173432144 12.529 100 86 166 1 1415594352 223831056 18331152
02:48:45 2578 8192000000 10308720496 10720059392 1184827760 12.635 100 72 140 0 1428436592 227775552 15833280
02:49:53 1459 8192000000 10355237712 10762657792 1187848592 12.663 100 93 182 3 1467230080 258671184 20710304
02:50:58 784 8192000000 10392939328 10803011584 1185328880 12.640 100 93 183 1 1499769168 293393408 21046880
02:52:04 1832 8192000000 10414501088 10832547840 1191278000 12.695 100 94 182 0 1522371312 310074928 21018384
02:53:10 1495 8192000000 10421058992 10854432768 1173972016 12.534 99 90 173 1 1524825952 330129248 20724688
02:54:20 866 8192000000 10444984512 10870255616 1188748496 12.672 100 96 179 0 1545864368 336092592 21023280
02:55:26 1211 8192000000 10469978256 10882449408 1195044992 12.730 100 94 177 1 1573799440 357838624 20915824
02:56:33 1098 8192000000 10501314832 10930151424 1185952192 12.646 100 96 187 0 1603700832 394248800 23499840
02:57:39 969 8192000000 10494573824 10930769920 1181055424 12.600 99 97 192 3 1596313664 392293776 22964464
02:58:43 799 8192000000 10549960848 10975019008 1196517728 12.744 100 99 201 0 1654480496 433838480 24124288
02:59:45 2219 8192000000 10545961424 10974023680 1194824768 12.728 100 79 155 0 1649601872 435673424 19103680
03:01:00 1973 8192000000 10598752704 11055525888 1190412800 12.687 100 79 152 0 1696829600 486952960 19463840
03:02:09 760 8192000000 10639466832 11099365376 1204984368 12.823 100 96 188 1 1740764768 513926688 21853712
03:03:12 13621 8192000000 10645246576 11105603584 1204437776 12.818 100 50 100 0 1736862960 518456480 13968704
03:04:29 4471 8192000000 10595760496 11100786688 1190597136 12.689 100 52 104 0 1697006912 492793312 13616464
03:05:54 7643 8192000000 10530924736 11029184512 1180434384 12.594 100 35 70 0 1648451104 458605520 9411200
03:07:16 13658 8192000000 10476337840 10958921728 1174334432 12.537 100 31 62 0 1611613056 429071952 8206672
03:08:35 33469 8192000000 10369919408 10813485056 1151214352 12.321 99 27 54 0 1473978160 315878208 6885600
03:10:09 19060 8192000000 10274320192 10767417344 1154749264 12.354 100 28 56 0 1361433040 200028608 6655168
03:11:48 52078 8192000000 10103360032 10581352448 1155144368 12.358 100 20 40 1 1213979872 55222832 3612672
03:14:27 46189 8192000000 10073814592 10534363136 1109056448 11.923 97 49 74 2 1157956576 40652080 8248048
03:16:20 69762 8192000000 10084542144 10563923968 1145748080 12.270 100 26 48 0 1196985152 46772128 4464944
03:18:30 84357 8192000000 10158933936 10612031488 1128564432 12.108 98 37 74 1 1228181600 91119216 8497952
03:20:59 87906 8192000000 10107842256 10563665920 1043732528 11.301 91 25 50 1 1114821408 65751472 5337408
03:23:28 16190 8192000000 10139607760 10575056896 1124223680 12.067 99 30 60 0 1232865776 100942432 7699664
03:24:45 11708 8192000000 10136836544 10599661568 1110594704 11.938 98 54 101 1 1224148720 102940640 10613376
03:26:04 131195 8192000000 10118956816 10594918400 1114666864 11.977 98 16 32 0 1201631040 83624976 3339200
03:29:20 136430 8192000000 10055467520 10544541696 1087349088 11.717 96 14 28 0 1193158960 102209632 3600240
03:32:37 31380 8192000000 10078165600 10536796160 1112653456 11.958 98 41 78 2 1147141936 26452432 8036048
03:34:21 38369 8192000000 10114987744 10561884160 1120595712 12.033 99 29 58 0 1203467824 77169616 5702496
03:35:59 34126 8192000000 10082301408 10532073472 1113647888 11.967 98 20 40 0 1156996064 39449792 3898384
03:37:34 55509 8192000000 10108142160 10610151424 1114974992 11.979 98 35 71 0 1198971296 76846000 7150304
03:39:32 85675 8192000000 10075813488 10568437760 1129977520 12.121 99 20 40 0 1204651776 70624080 4050176
03:41:58 65991 8192000000 10082997120 10552934400 1138840528 12.205 100 22 44 0 1218723664 74911968 4971168
03:44:05 36193 8192000000 10099552032 10566619136 1113565408 11.966 98 42 84 1 1173356800 51248752 8542640
03:45:43 33717 8192000000 10078826464 10498134016 1102279888 11.859 97 47 91 0 1143598240 31942640 9375712
03:47:17 81719 8192000000 10091147648 10528956416 1125274240 12.077 100 53 106 0 1183981776 47305904 11401632
03:49:53 16208 8192000000 10072329856 10504257536 1104743472 11.883 98 42 85 0 1164094928 51504192 7847264
03:51:09 56489 8192000000 10078438848 10526892032 1129139104 12.113 100 20 40 0 1178173120 45147328 3886688
03:53:33 59756 8192000000 10133171264 10584596480 1116104352 11.990 98 15 30 0 1204666352 84975824 3586176
03:55:33 61610 8192000000 10120124304 10629591040 1111569648 11.947 98 12 24 0 1236027104 121655296 2802160
03:57:35 73576 8192000000 10060890160 10532548608 1097521664 11.814 97 22 44 0 1147975328 45552272 4901392
03:59:49 47021 8192000000 10139950064 10593333248 1138142080 12.198 100 41 79 0 1259140736 112649808 8348848
04:01:39 64226 8192000000 10151641152 10613637120 1119580608 12.023 98 33 66 0 1227440016 100853472 7005936
04:03:47 78156 8192000000 10136819136 10659868672 1129929408 12.121 99 16 32 1 1272412704 138601552 3881744
04:06:28 88646 8192000000 10121888832 10568417280 1093463760 11.776 96 17 34 1 1171378512 74136400 3778352
04:09:00 93764 8192000000 10090510944 10583052288 1117599728 12.004 99 27 54 0 1209916336 86552960 5763648
04:11:34 75827 8192000000 10074663216 10531020800 1121243664 12.039 99 8 15 0 1177232944 54686048 1303232
04:13:55 64397 8192000000 10078184640 10539855872 1122063584 12.046 99 10 18 0 1166622752 42766800 1792368
04:16:08 43699 8192000000 10092936912 10544566272 1139949008 12.215 100 23 46 1 1200775488 55586576 5239904
04:17:53 88557 8192000000 10055804320 10515607552 1102013344 11.857 97 17 34 0 1135074400 29279488 3781568
04:20:22 26940 8192000000 10125052864 10560892928 1139680112 12.213 100 39 78 0 1199581888 51475472 8426304
04:21:49 32289 8192000000 10108366496 10582708224 1118159696 12.010 98 33 66 0 1234875632 109643232 7072704
04:23:22 29837 8192000000 10122960784 10563018752 1108035376 11.914 97 38 75 0 1169956368 54604912 7316080
04:24:54 24706 8192000000 10166544640 10605072384 1135710528 12.175 100 47 94 0 1268717984 123496064 9511392
04:26:48 43649 8192000000 10072817360 10515804160 1120543456 12.032 99 24 48 0 1172641536 47179088 4918992
04:28:33 96199 8192000000 10077091104 10568785920 1111495584 11.947 98 31 61 0 1173984032 56329488 6158960
04:31:14 61487 8192000000 10075625520 10540158976 1119184480 12.019 99 10 20 0 1174994928 53626000 2184448
04:33:16 72562 8192000000 10064920928 10514833408 1094110800 11.782 97 18 34 0 1137605392 40455440 3039152
04:36:31 51328 8192000000 10085925104 10541932544 1115925360 11.988 99 20 39 0 1156174816 35871904 4377552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-02-2023 04:38:44 to 11-02-2023 04:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-02-2023 04:39:01 to 11-02-2023 04:39:03.

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          223524
MON$OLDEST_ACTIVE               223525
MON$OLDEST_SNAPSHOT             223525
MON$NEXT_TRANSACTION            223525
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                       2070752
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2899 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2899 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2899 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 ---
--- end of diff output ---

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

11.02.23 04:39:04. 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-02-11 04:39:07 No severe PSQL-related problems occured
11.02.23 04:39:08. End of report.