Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.04.2022 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 16623424 kB
MemAvailable 31558548 kB
Buffers 20 kB
Cached 15060424 kB
SwapCached 2868 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.2
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 2022-04-26 04:37:15
DTS_END                         2022-04-26 04:37:15.1600
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/45562
TRN_ID                          221167
ATT_ID                          647
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: 8637.66 28146 1036519
customer order: creation 164.96 458 19795
customer order: refuse 14.01 381 1681
order to supplier: creation 44.61 1470 5353
order to supplier: removal 3.32 149618 398
invoice (draft): creation 44.43 3531 5331
invoice (draft): removal 46.40 975 5568
invoice accept: apply 41.57 25556 4988
invoice accept: cancel 41.38 1290 4966
customer reserve: creation 3835.78 245 460293
customer reserve: removal 4128.66 95 495439
realization accept: apply 80.61 250 9673
realization accept: cancel 66.78 170 8013
payment from customer: creation 57.49 170 6899
payment from customer: removal 6.03 118 723
payment to supplier: creation 46.03 180 5524
payment to supplier: removal 3.05 131 366
service: total inventory turnovers 3.73 3991 447
service: total monetary turnovers 7.82 344 938
service: aggregate perf. data 0.99 16351 119
service: refresh index statistics 0.04 357594 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-04-2022 04:38:51 to 26-04-2022 04:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9780
TEST_TIME 2 8360
TEST_TIME 3 8626
TEST_TIME 4 8279
TEST_TIME 5 7322
TEST_TIME 6 7324
TEST_TIME 7 7212
TEST_TIME 8 7302
TEST_TIME 9 7157
TEST_TIME 10 7342
TEST_TIME 11 7649
TEST_TIME 12 7645
TEST_TIME 13 8154
TEST_TIME 14 8146
TEST_TIME 15 8046
TEST_TIME 16 8078
TEST_TIME 17 8114
TEST_TIME 18 8240
TEST_TIME 19 8317
TEST_TIME 20 8313
TEST_TIME 21 8274
TEST_TIME 22 8487
TEST_TIME 23 9020
TEST_TIME 24 9603
TEST_TIME 25 9806
TEST_TIME 26 10438
TEST_TIME 27 10469
TEST_TIME 28 11098
TEST_TIME 29 11158
TEST_TIME 30 11261
TEST_TIME 31 11024
TEST_TIME 32 10866
TEST_TIME 33 10661
TEST_TIME 34 10672
TEST_TIME 35 10685
TEST_TIME 36 10676
TEST_TIME 37 10491
TEST_TIME 38 10490
TEST_TIME 39 10402
TEST_TIME 40 10347
TEST_TIME 41 10190
TEST_TIME 42 10065
TEST_TIME 43 9981
TEST_TIME 44 9847
TEST_TIME 45 9674
TEST_TIME 46 9717
TEST_TIME 47 9549
TEST_TIME 48 9428
TEST_TIME 49 9403
TEST_TIME 50 9431
TEST_TIME 51 9354
TEST_TIME 52 9249
TEST_TIME 53 9236
TEST_TIME 54 9211
TEST_TIME 55 9172
TEST_TIME 56 9199
TEST_TIME 57 9209
TEST_TIME 58 9141
TEST_TIME 59 9233
TEST_TIME 60 9253
TEST_TIME 61 9267
TEST_TIME 62 9265
TEST_TIME 63 9208
TEST_TIME 64 9238
TEST_TIME 65 9387
TEST_TIME 66 9418
TEST_TIME 67 9305
TEST_TIME 68 9230
TEST_TIME 69 9160
TEST_TIME 70 9158
TEST_TIME 71 9073
TEST_TIME 72 9042
TEST_TIME 73 8959
TEST_TIME 74 8902
TEST_TIME 75 8832
TEST_TIME 76 8862
TEST_TIME 77 8801
TEST_TIME 78 8774
TEST_TIME 79 8763
TEST_TIME 80 8811
TEST_TIME 81 8824
TEST_TIME 82 8778
TEST_TIME 83 8798
TEST_TIME 84 8962
TEST_TIME 85 8966
TEST_TIME 86 8961
TEST_TIME 87 9017
TEST_TIME 88 8972
TEST_TIME 89 8985
TEST_TIME 90 9064
TEST_TIME 91 8990
TEST_TIME 92 8949
TEST_TIME 93 8905
TEST_TIME 94 8857
TEST_TIME 95 8816
TEST_TIME 96 8781
TEST_TIME 97 8774
TEST_TIME 98 8727
TEST_TIME 99 8713
TEST_TIME 100 8697
TEST_TIME 101 8710
TEST_TIME 102 8698
TEST_TIME 103 8666
TEST_TIME 104 8683
TEST_TIME 105 8764
TEST_TIME 106 8729
TEST_TIME 107 8732
TEST_TIME 108 8788
TEST_TIME 109 8776
TEST_TIME 110 8823
TEST_TIME 111 8833
TEST_TIME 112 8888
TEST_TIME 113 8855
TEST_TIME 114 8828
TEST_TIME 115 8804
TEST_TIME 116 8769
TEST_TIME 117 8752
TEST_TIME 118 8743
TEST_TIME 119 8725
TEST_TIME 120 8668

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-04-2022 04:38:55 to 26-04-2022 04:38:58.

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 1457914 1457914 0 0.00 0 15564 13 0 0
doc_list_biud 1457902 1457902 0 0.00 0 65742 38 0 0
sp_add_invoice_to_stock 5090 4988 102 2.00 65 254777 25557 0 102
sp_cancel_adding_invoice 4966 4966 0 0.00 8 88615 1291 0 0
sp_cancel_client_order 1681 1681 0 0.00 6 14583 381 0 0
sp_cancel_customer_reserve 495439 495439 0 0.00 1 48181 96 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 4 5744 119 0 0
sp_cancel_pay_to_supplier 366 366 0 0.00 4 3330 132 0 0
sp_cancel_supplier_invoice 5576 5568 8 0.14 3 65752 976 0 8
sp_cancel_supplier_order 398 398 0 0.00 11 1243715 149618 0 0
sp_cancel_write_off 8013 8013 0 0.00 2 15570 170 0 0
sp_client_order 19795 19795 0 0.00 6 25864 459 0 0
sp_customer_reserve 460293 460293 0 0.00 1 60773 246 0 0
sp_fill_shopping_cart 30464 30464 0 0.00 1 24041 91 0 0
sp_fill_shopping_cart_clo_res 460248 460248 0 0.00 0 11492 19 0 0
sp_get_clo_for_invoice 4938 4938 0 0.00 0 25543 364 0 0
sp_kill_cost_storno 14068 14068 0 0.00 0 13381 69 0 0
sp_kill_qstorno_ret_qs2qd 1004857 1004857 0 0.00 0 65739 42 0 0
sp_kill_qty_storno 530673 530673 0 0.00 0 65742 96 0 0
sp_lock_selected_doc 20269 20269 0 0.00 0 6831 7 0 0
sp_make_cost_storno 27033 27033 0 0.00 0 15060 76 0 0
sp_multiply_rows_for_pdistr 27032 27032 0 0.00 0 7894 35 0 0
sp_multiply_rows_for_qdistr 19794 19794 0 0.00 0 13097 161 0 0
sp_pay_from_customer 6899 6899 0 0.00 5 13350 170 0 0
sp_pay_to_supplier 5524 5524 0 0.00 7 15387 181 0 0
sp_payment_common 12423 12423 0 0.00 2 12299 40 0 0
sp_qd_handle_on_cancel_clo 1681 1681 0 0.00 1 14549 304 0 0
sp_qd_handle_on_invoice_upd_sts 9901 9901 0 0.00 1 27220 513 0 0
sp_qd_handle_on_reserve_upd_sts 9673 9673 0 0.00 0 13302 61 0 0
sp_reserve_write_off 9673 9673 0 0.00 9 15100 250 0 0
sp_supplier_invoice 5331 5331 0 0.00 9 93196 3531 0 0
sp_supplier_order 5353 5353 0 0.00 8 41630 1470 0 0
srv_aggregate_perf_data 119 119 0 0.00 2383 212746 16352 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 766 125449 40644 0 0
srv_find_qd_qs_mism 987555 987555 0 0.00 0 15539 11 0 0
srv_make_invnt_saldo 447 447 0 0.00 62 73576 3992 0 0
srv_make_money_saldo 938 938 0 0.00 1 22480 344 0 0
srv_recalc_idx_stat 5 5 0 0.00 259847 424642 357594 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 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 4995 4995 0 0.00 1 13684 880 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 1 6201 570 0 0
t$perf-norm:sp_cancel_client_order 1681 1681 0 0.00 0 4109 28 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 7538 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 1278 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 366 366 0 0.00 0 141 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 584 27 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 113285 5966 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 1898 19 0 0
t$perf-norm:sp_client_order 19796 19796 0 0.00 0 7890 32 0 0
t$perf-norm:sp_customer_reserve 969 969 0 0.00 0 1502 13 0 0
t$perf-norm:sp_pay_from_customer 6899 6899 0 0.00 0 12644 19 0 0
t$perf-norm:sp_pay_to_supplier 5524 5524 0 0.00 0 7887 18 0 0
t$perf-norm:sp_reserve_write_off 9673 9673 0 0.00 0 13226 21 0 0
t$perf-norm:sp_supplier_invoice 5331 5331 0 0.00 0 8231 32 0 0
t$perf-norm:sp_supplier_order 5353 5353 0 0.00 0 13764 30 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 227 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 2503 40 0 0
t$perf-norm:srv_make_invnt_saldo 447 447 0 0.00 0 531 15 0 0
t$perf-norm:srv_make_money_saldo 938 938 0 0.00 0 1588 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 317 107 0 0
v_add_invoice_to_stock 10064 9860 204 2.02 0 3854 22 0 204
v_all_customers 35642 35642 0 0.00 0 174 3 0 0
v_all_suppliers 29372 29372 0 0.00 0 64 4 0 0
v_all_wares 39582 39582 0 0.00 0 59 3 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 2600 18 0 0
v_cancel_client_order 3362 3362 0 0.00 0 2096 9 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 5731 16 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 359 8 0 0
v_cancel_payment_to_supplier 732 732 0 0.00 0 3256 17 0 0
v_cancel_supplier_invoice 848 832 16 1.88 0 1960 24 0 16
v_cancel_supplier_order 796 796 0 0.00 0 486 10 0 0
v_cancel_write_off 1998 1998 0 0.00 0 2041 23 0 0
v_max_id_clo_ord 5349 5349 0 0.00 0 4220 6 0 0
v_max_id_clo_res 969 969 0 0.00 0 4015 17 0 0
v_max_id_ord_sup 5323 5323 0 0.00 0 6098 8 0 0
v_max_non_paid_invoice 1510 1510 0 0.00 0 91 7 0 0
v_max_non_paid_realizn 3885 3885 0 0.00 0 783 6 0 0
v_min_id_clo_ord 5349 5349 0 0.00 0 6565 16 0 0
v_min_id_clo_res 969 969 0 0.00 0 9479 27 0 0
v_min_id_ord_sup 5323 5323 0 0.00 0 6309 11 0 0
v_min_non_paid_invoice 5524 5524 0 0.00 0 12152 12 0 0
v_min_non_paid_realizn 6899 6899 0 0.00 0 6398 17 0 0
v_our_firm 9962 9962 0 0.00 0 65 4 0 0
v_reserve_write_off 19346 19346 0 0.00 0 4504 10 0 0
x_lock_depdocs_on_canc_invoice 10118 10118 0 0.00 0 18290 131 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 4515 358 0 0
x_make_qstorno_1000_1200 5353 5353 0 0.00 3 35609 1281 0 0
x_make_qstorno_1200_2000 5331 5331 0 0.00 4 93058 3271 0 0
x_make_qstorno_2100_3300 460293 460293 0 0.00 0 60772 215 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-04-2022 04:38:59 to 26-04-2022 04:39:03.

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:19 38 8192000000 8810880224 8826904576 8979312 0.109 4 2 3 0 9134960 94288 61360
02:08:20 36814 8192000000 9732794512 10033328128 804393328 8.941 100 89 178 1 834220288 7789360 22037600
02:10:04 16575 8192000000 9768770288 10079420416 844543088 9.345 100 88 177 0 878520720 12746224 21231408
02:11:26 23042 8192000000 9819329584 10139918336 875250592 9.652 100 87 173 0 922806832 27677008 19879232
02:12:55 12458 8192000000 9888433424 10225283072 933040224 10.225 100 83 163 1 994242960 43311728 17891008
02:14:13 3249 8192000000 9926305344 10274050048 975965024 10.645 100 88 173 0 1042508432 49024336 17519072
02:15:18 1429 8192000000 9996040816 10352721920 1009416592 10.970 100 94 182 1 1109650320 81492384 18741344
02:16:22 1696 8192000000 10039330224 10405326848 1029893472 11.167 100 91 176 0 1151388240 104310944 17183824
02:17:25 894 8192000000 10023879616 10398011392 1049527456 11.356 100 95 186 0 1146643952 77905968 19210528
02:18:28 831 8192000000 10006832192 10381942784 1059210720 11.449 100 89 177 0 1127742256 49994432 18537104
02:19:31 1088 8192000000 10031957200 10407927808 1068814848 11.541 100 100 194 2 1151447872 63490064 19142960
02:20:33 935 8192000000 10057173504 10442227712 1074679696 11.597 100 89 176 0 1176313264 83588000 18045568
02:21:34 889 8192000000 10047017344 10432753664 1076450384 11.614 100 96 187 1 1165482944 70562272 18470288
02:22:36 859 8192000000 10051005600 10439852032 1081698016 11.664 100 92 180 0 1172403200 72020128 18685056
02:23:37 1167 8192000000 10062260544 10448703488 1090675968 11.749 100 96 182 4 1186863136 77099536 19087632
02:24:40 1077 8192000000 10061008144 10448920576 1091612128 11.758 100 94 179 3 1181882160 71448208 18821824
02:25:41 1275 8192000000 10064024336 10454634496 1098770496 11.826 100 99 192 0 1180174144 61753696 19649952
02:26:43 1188 8192000000 10050695680 10440634368 1098660416 11.825 100 97 184 0 1173902928 54814848 20427664
02:27:45 1132 8192000000 10053797232 10439667712 1093566624 11.777 100 89 174 2 1175291200 64402080 17322496
02:28:47 2963 8192000000 10061321744 10447577088 1093919616 11.780 100 81 149 3 1178048032 69114656 15013760
02:29:51 1061 8192000000 10039471104 10425413632 1082825248 11.674 100 92 179 2 1160851216 58659280 19366688
02:30:53 6105 8192000000 10034512768 10423791616 1072861808 11.579 99 67 128 3 1151588608 64666480 14060320
02:32:00 894 8192000000 9972805616 10335465472 1005678368 10.934 99 93 181 1 1090323168 65708208 18936592
02:33:02 5315 8192000000 10000839520 10396348416 1055149392 11.410 100 53 99 1 1131537296 65868416 10519488
02:34:09 1137 8192000000 10027840320 10403565568 1044538416 11.308 100 87 167 2 1147885120 85221712 18124992
02:35:11 1373 8192000000 9986678240 10359099392 1036774944 11.234 100 94 181 1 1114236448 58642192 18819312
02:36:13 962 8192000000 9998984144 10374369280 1039466576 11.260 100 93 181 1 1116178640 58404992 18307072
02:37:15 1771 8192000000 10009831920 10385580032 1045602800 11.318 100 97 183 1 1130201024 66771536 17826688
02:38:29 4564 8192000000 10019960736 10411061248 1062840464 11.484 100 63 124 1 1144087808 68850752 12396592
02:39:38 19905 8192000000 10084145712 10473328640 1083368368 11.680 100 72 140 0 1205449376 107561904 14519104
02:41:02 2606 8192000000 10093386544 10484916224 1103234480 11.868 100 82 150 1 1210328752 89867536 17226736
02:42:10 7120 8192000000 10113102272 10505748480 1110909968 11.941 100 68 134 1 1225459120 100522304 14026848
02:43:22 4053 8192000000 10152373136 10559885312 1123052752 12.056 100 59 114 0 1272665296 136873104 12739440
02:44:32 1600 8192000000 10179740448 10568908800 1127082304 12.094 100 84 163 2 1289233152 144029360 18121488
02:45:36 1300 8192000000 10212433552 10599628800 1118836864 12.016 99 94 174 0 1325631072 187111296 19682912
02:46:42 1822 8192000000 10237770720 10634489856 1139700016 12.213 100 87 168 0 1350105920 192238144 18167760
02:47:49 3445 8192000000 10236875008 10663854080 1139799264 12.214 99 78 152 0 1348185200 191146480 17239456
02:48:55 2658 8192000000 10268169344 10680508416 1170390432 12.500 100 82 162 0 1379085808 190664432 18030944
02:50:00 817 8192000000 10303135632 10721161216 1182163552 12.610 100 99 191 1 1412115728 207856320 22095856
02:51:03 887 8192000000 10328673648 10731630592 1190825760 12.691 100 92 171 3 1435713536 226592496 18295280
02:52:09 2263 8192000000 10345967920 10760835072 1194064448 12.721 100 73 145 0 1452270384 241844384 16361552
02:53:13 768 8192000000 10404099840 10821447680 1210228192 12.871 100 96 193 0 1508280592 275203040 22849360
02:54:16 1119 8192000000 10404019728 10828955648 1215730160 12.922 100 93 183 3 1509980112 272128496 22121456
02:55:21 759 8192000000 10461812704 10867097600 1206887360 12.840 100 97 190 0 1567004192 337649504 22467328
02:56:23 1093 8192000000 10505642672 10914201600 1194684160 12.727 100 98 196 2 1608336752 391137920 22514672
02:57:26 976 8192000000 10533663008 10945318912 1185286032 12.639 100 93 183 1 1631713520 423276336 23151152
02:58:31 1653 8192000000 10541746320 10969894912 1181324064 12.603 100 96 190 1 1638969552 434696240 22949248
02:59:35 1163 8192000000 10593672752 11040251904 1177992224 12.571 100 85 168 2 1697747552 498005296 21750032
03:00:40 21771 8192000000 10478870544 10986053632 1173565600 12.530 100 43 84 1 1597593088 412614320 11413168
03:02:08 17119 8192000000 10358911968 10860494848 1152738688 12.335 100 31 62 0 1509525808 348347728 8439392
03:03:29 46060 8192000000 10266463392 10736705536 1111344608 11.945 97 22 44 0 1339530640 222654368 5531664
03:05:17 23179 8192000000 10147191872 10593341440 1130640336 12.127 100 23 46 1 1258383376 121807984 5935056
03:06:40 33400 8192000000 10127110544 10597085184 1106060768 11.895 97 20 40 1 1224310080 113739184 4510128
03:08:20 61245 8192000000 10162245472 10613047296 1142966912 12.243 100 55 106 0 1281778128 127820720 10990496
03:10:34 17914 8192000000 10171014560 10607292416 1137909760 12.196 99 58 97 0 1266425328 117265920 11249648
03:12:18 63461 8192000000 10104767856 10583986176 1124129920 12.066 99 16 32 1 1223192896 95332912 3730064
03:14:33 77320 8192000000 10064209680 10519552000 1126937920 12.092 99 33 67 2 1185324960 50174608 8212432
03:17:00 125448 8192000000 10045605552 10499719168 1080109216 11.649 95 19 36 2 1125671552 42496976 3065360
03:20:13 114964 8192000000 10068738784 10516770816 1118977616 12.017 98 28 54 1 1196325328 71602816 5744896
03:23:11 78024 8192000000 10074413536 10519879680 1133896064 12.158 99 22 44 0 1180977216 42370160 4710992
03:25:52 37202 8192000000 10147085952 10580803584 1158948384 12.393 100 36 72 0 1266633040 99791792 7892864
03:27:31 90976 8192000000 10091404384 10581942272 1119086896 12.018 97 15 30 1 1176362992 54417248 2858848
03:30:02 43587 8192000000 10065198464 10544447488 1107038352 11.904 97 7 15 0 1155203024 46646992 1517680
03:31:48 34826 8192000000 10070069792 10522349568 1100830336 11.846 97 15 30 0 1149523888 45282272 3411280
03:33:23 62339 8192000000 10085719584 10507534336 1110029648 11.933 98 42 83 2 1159577776 40601584 8946544
03:35:27 21761 8192000000 10116849200 10547625984 1135800048 12.176 100 39 78 1 1216815696 73081408 7934240
03:36:49 72133 8192000000 10076382992 10549010432 1127129184 12.094 99 17 34 0 1195208608 64432288 3647136
03:39:02 84767 8192000000 10131815936 10582712320 1125166720 12.076 98 23 46 1 1218328896 88239760 4922416
03:41:32 55996 8192000000 10142971584 10626678784 1139686144 12.213 99 20 40 2 1268185088 124137312 4361632
03:43:31 122529 8192000000 10073701632 10501054464 1099479040 11.833 97 39 76 2 1137708176 30279152 7949984
03:46:34 5995 8192000000 10045456384 10457284608 1105835056 11.893 99 63 116 2 1146615728 27535968 13244704
03:47:41 69033 8192000000 10064949152 10526564352 1130553088 12.127 100 20 40 0 1184775632 49877376 4345168
03:49:50 77961 8192000000 10068381808 10504982528 1106990992 11.904 98 19 37 1 1159430848 48845136 3594720
03:52:12 86262 8192000000 10091702496 10565746688 1114122560 11.971 99 12 24 0 1185193904 68185392 2885952
03:55:21 57715 8192000000 10108780928 10572206080 1138292192 12.199 99 24 47 1 1191541040 48323920 4924928
03:57:43 47248 8192000000 10121993136 10554703872 1115012048 11.980 97 28 54 0 1197840880 77437392 5391440
03:59:43 43173 8192000000 10189185456 10651942912 1139502976 12.211 99 27 54 0 1296233200 150779952 5950272
04:01:30 40279 8192000000 10051484000 10563428352 1107296208 11.907 98 15 30 0 1210434096 99053408 4084480
04:03:13 23851 8192000000 10099423200 10525732864 1108090240 11.914 98 42 82 0 1173134832 57020336 8024256
04:04:39 43942 8192000000 10086350112 10524352512 1120477248 12.031 99 40 80 2 1194549936 66637136 7435552
04:06:29 28603 8192000000 10144925248 10587557888 1111710912 11.949 98 27 54 1 1227201808 110003744 5487152
04:08:04 107219 8192000000 10070462272 10517803008 1091638768 11.758 97 32 64 0 1145886480 47603216 6644496
04:11:34 79350 8192000000 10075577024 10537771008 1125918704 12.083 99 14 27 0 1186232272 57347072 2966496
04:13:53 57592 8192000000 10035418816 10525257728 1102555504 11.862 98 10 20 0 1141526720 36705792 2265424
04:15:54 72994 8192000000 10050273072 10501795840 1081074752 11.658 96 15 30 1 1117108768 33093472 2940544
04:18:25 34866 8192000000 10069196128 10512084992 1107820496 11.912 98 27 55 0 1168326192 53929360 6576336
04:20:00 39711 8192000000 10116780304 10551062528 1128666496 12.109 99 24 48 0 1203715504 69725840 5323168
04:21:57 78794 8192000000 10090665712 10575556608 1114768544 11.978 98 14 26 2 1188575376 70865936 2940896
04:24:16 50991 8192000000 10088321024 10571214848 1114907232 11.979 98 23 45 0 1218833520 99208288 4718000
04:26:11 54492 8192000000 10121973648 10570481664 1102929120 11.865 97 37 74 1 1206147056 95745392 7472544
04:28:10 72399 8192000000 10072830176 10523271168 1122501184 12.051 99 19 36 1 1176810000 50058784 4250032
04:30:23 104692 8192000000 10077387872 10520862720 1134253968 12.161 100 34 66 3 1205923264 64668208 7001088
04:33:23 16909 8192000000 10091973744 10518421504 1139211696 12.208 99 59 109 4 1190012176 39996464 10804016
04:34:40 57261 8192000000 10071235568 10566295552 1132434848 12.144 99 10 20 1 1213260880 78536464 2289568
04:36:40 43899 8192000000 9234049296 9527443456 377150656 4.401 34 6 12 0 388478512 9972304 1355552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-04-2022 04:39:05 to 26-04-2022 04:39:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 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 26-04-2022 04:39:22 to 26-04-2022 04:39:24.

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          222334
MON$OLDEST_ACTIVE               222335
MON$OLDEST_SNAPSHOT             222335
MON$NEXT_TRANSACTION            222335
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                       2037920
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

26.04.22 04:39:25. 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
2022-04-26 04:39:27 No severe PSQL-related problems occured
26.04.22 04:39:29. End of report.