Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.03.2023 07: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 14574616 kB
MemAvailable 30412924 kB
Buffers 0 kB
Cached 17093768 kB
SwapCached 3084 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-02 07:37:26
DTS_END                         2023-03-02 07:37:26.0930
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/40682
TRN_ID                          221411
ATT_ID                          566
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: 8834.86 27441 1060183
customer order: creation 175.48 467 21058
customer order: refuse 14.73 479 1767
order to supplier: creation 46.19 1399 5543
order to supplier: removal 3.39 169800 407
invoice (draft): creation 46.86 3482 5623
invoice (draft): removal 49.47 1089 5936
invoice accept: apply 41.29 27909 4955
invoice accept: cancel 44.16 1335 5299
customer reserve: creation 3846.18 259 461541
customer reserve: removal 4277.37 103 513284
realization accept: apply 84.77 324 10172
realization accept: cancel 72.72 176 8727
payment from customer: creation 59.76 227 7171
payment from customer: removal 5.69 155 683
payment to supplier: creation 50.38 220 6046
payment to supplier: removal 3.33 172 399
service: total inventory turnovers 3.67 5305 440
service: total monetary turnovers 8.38 476 1006
service: aggregate perf. data 1.01 19548 121
service: refresh index statistics 0.04 315894 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-03-2023 07:38:26 to 02-03-2023 07:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7857
TEST_TIME 2 7225
TEST_TIME 3 6852
TEST_TIME 4 7133
TEST_TIME 5 6728
TEST_TIME 6 7561
TEST_TIME 7 7365
TEST_TIME 8 7270
TEST_TIME 9 6909
TEST_TIME 10 7033
TEST_TIME 11 7136
TEST_TIME 12 6972
TEST_TIME 13 6775
TEST_TIME 14 6847
TEST_TIME 15 6953
TEST_TIME 16 7058
TEST_TIME 17 7175
TEST_TIME 18 7250
TEST_TIME 19 7383
TEST_TIME 20 7809
TEST_TIME 21 7745
TEST_TIME 22 7894
TEST_TIME 23 8190
TEST_TIME 24 8322
TEST_TIME 25 8582
TEST_TIME 26 8507
TEST_TIME 27 8509
TEST_TIME 28 8410
TEST_TIME 29 8442
TEST_TIME 30 8568
TEST_TIME 31 8634
TEST_TIME 32 8733
TEST_TIME 33 8781
TEST_TIME 34 8913
TEST_TIME 35 8903
TEST_TIME 36 9163
TEST_TIME 37 9357
TEST_TIME 38 9425
TEST_TIME 39 9764
TEST_TIME 40 10056
TEST_TIME 41 10465
TEST_TIME 42 10452
TEST_TIME 43 10241
TEST_TIME 44 10418
TEST_TIME 45 10391
TEST_TIME 46 10279
TEST_TIME 47 10155
TEST_TIME 48 10113
TEST_TIME 49 10082
TEST_TIME 50 9973
TEST_TIME 51 9967
TEST_TIME 52 9935
TEST_TIME 53 9833
TEST_TIME 54 9780
TEST_TIME 55 9714
TEST_TIME 56 9780
TEST_TIME 57 9691
TEST_TIME 58 9670
TEST_TIME 59 9653
TEST_TIME 60 9581
TEST_TIME 61 9464
TEST_TIME 62 9394
TEST_TIME 63 9333
TEST_TIME 64 9379
TEST_TIME 65 9262
TEST_TIME 66 9262
TEST_TIME 67 9243
TEST_TIME 68 9181
TEST_TIME 69 9226
TEST_TIME 70 9180
TEST_TIME 71 9136
TEST_TIME 72 9075
TEST_TIME 73 9197
TEST_TIME 74 9108
TEST_TIME 75 9083
TEST_TIME 76 9105
TEST_TIME 77 9133
TEST_TIME 78 9103
TEST_TIME 79 9054
TEST_TIME 80 8991
TEST_TIME 81 8902
TEST_TIME 82 8965
TEST_TIME 83 8924
TEST_TIME 84 8922
TEST_TIME 85 8896
TEST_TIME 86 8900
TEST_TIME 87 8859
TEST_TIME 88 8841
TEST_TIME 89 8924
TEST_TIME 90 8927
TEST_TIME 91 9011
TEST_TIME 92 8984
TEST_TIME 93 9087
TEST_TIME 94 9120
TEST_TIME 95 9136
TEST_TIME 96 9106
TEST_TIME 97 9047
TEST_TIME 98 8979
TEST_TIME 99 9005
TEST_TIME 100 8998
TEST_TIME 101 8941
TEST_TIME 102 8904
TEST_TIME 103 8885
TEST_TIME 104 8824
TEST_TIME 105 8782
TEST_TIME 106 8750
TEST_TIME 107 8806
TEST_TIME 108 8820
TEST_TIME 109 8816
TEST_TIME 110 8816
TEST_TIME 111 8799
TEST_TIME 112 8798
TEST_TIME 113 8795
TEST_TIME 114 8825
TEST_TIME 115 8828
TEST_TIME 116 8856
TEST_TIME 117 8874
TEST_TIME 118 8906
TEST_TIME 119 8922
TEST_TIME 120 8864

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-03-2023 07:38:30 to 02-03-2023 07:38:33.

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 1482954 1482954 0 0.00 0 33379 15 0 0
doc_list_biud 1482941 1482941 0 0.00 0 39437 43 0 0
sp_add_invoice_to_stock 5110 4955 155 3.03 64 179273 27909 0 155
sp_cancel_adding_invoice 5299 5299 0 0.00 8 74580 1336 0 0
sp_cancel_client_order 1767 1767 0 0.00 6 11267 479 0 0
sp_cancel_customer_reserve 513284 513284 0 0.00 1 39448 104 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 4 4741 156 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 4 3777 173 0 0
sp_cancel_supplier_invoice 5953 5936 17 0.28 3 19381 1089 0 17
sp_cancel_supplier_order 407 407 0 0.00 21 1403113 169801 0 0
sp_cancel_write_off 8727 8727 0 0.00 2 30983 176 0 0
sp_client_order 21058 21058 0 0.00 5 34340 467 0 0
sp_customer_reserve 461541 461541 0 0.00 0 62051 259 0 0
sp_fill_shopping_cart 32215 32215 0 0.00 1 15803 98 0 0
sp_fill_shopping_cart_clo_res 461499 461499 0 0.00 0 19412 20 0 0
sp_get_clo_for_invoice 4906 4906 0 0.00 0 34083 321 0 0
sp_kill_cost_storno 15108 15108 0 0.00 0 10684 83 0 0
sp_kill_qstorno_ret_qs2qd 1041638 1041638 0 0.00 0 39432 45 0 0
sp_kill_qty_storno 550498 550498 0 0.00 0 39436 106 0 0
sp_lock_selected_doc 20847 20847 0 0.00 0 10204 8 0 0
sp_make_cost_storno 28295 28295 0 0.00 0 13040 90 0 0
sp_multiply_rows_for_pdistr 28294 28294 0 0.00 0 33372 50 0 0
sp_multiply_rows_for_qdistr 21058 21058 0 0.00 0 8323 155 0 0
sp_pay_from_customer 7171 7171 0 0.00 5 33386 228 0 0
sp_pay_to_supplier 6046 6046 0 0.00 6 13088 221 0 0
sp_payment_common 13216 13216 0 0.00 2 12640 53 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 11262 388 0 0
sp_qd_handle_on_invoice_upd_sts 10205 10205 0 0.00 0 37537 641 0 0
sp_qd_handle_on_reserve_upd_sts 10172 10172 0 0.00 0 15988 69 0 0
sp_reserve_write_off 10172 10172 0 0.00 8 16127 324 0 0
sp_supplier_invoice 5623 5623 0 0.00 8 88412 3483 0 0
sp_supplier_order 5543 5543 0 0.00 8 39750 1400 0 0
srv_aggregate_perf_data 121 121 0 0.00 2617 231830 19549 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 702 143606 33884 0 0
srv_find_qd_qs_mism 1009588 1009588 0 0.00 0 20037 11 0 0
srv_make_invnt_saldo 440 440 0 0.00 49 116129 5305 0 0
srv_make_money_saldo 1006 1006 0 0.00 1 14551 477 0 0
srv_recalc_idx_stat 5 5 0 0.00 38505 472868 315895 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4962 4962 0 0.00 0 14305 1218 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 1 8646 551 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 1329 31 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 990 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 1731 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 877 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 1064 34 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 128214 8453 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 5917 28 0 0
t$perf-norm:sp_client_order 21058 21058 0 0.00 0 30820 47 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 4463 19 0 0
t$perf-norm:sp_pay_from_customer 7171 7171 0 0.00 0 6452 24 0 0
t$perf-norm:sp_pay_to_supplier 6046 6046 0 0.00 0 9914 24 0 0
t$perf-norm:sp_reserve_write_off 10172 10172 0 0.00 0 4168 27 0 0
t$perf-norm:sp_supplier_invoice 5623 5623 0 0.00 0 5690 43 0 0
t$perf-norm:sp_supplier_order 5544 5544 0 0.00 0 10413 43 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 489 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 18 4 0 0
t$perf-norm:srv_make_invnt_saldo 440 440 0 0.00 0 889 18 0 0
t$perf-norm:srv_make_money_saldo 1006 1006 0 0.00 0 369 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 231 105 0 0
v_add_invoice_to_stock 10108 9798 310 3.06 0 7516 33 0 310
v_all_customers 37910 37910 0 0.00 0 98 5 0 0
v_all_suppliers 31029 31029 0 0.00 0 131 5 0 0
v_all_wares 42112 42112 0 0.00 0 130 5 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 1482 18 0 0
v_cancel_client_order 3534 3534 0 0.00 0 2968 15 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 4666 16 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 2350 14 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 3751 23 0 0
v_cancel_supplier_invoice 844 810 34 4.02 0 1299 34 0 34
v_cancel_supplier_order 814 814 0 0.00 0 3151 18 0 0
v_cancel_write_off 1998 1998 0 0.00 0 2729 35 0 0
v_max_id_clo_ord 5542 5542 0 0.00 0 905 6 0 0
v_max_id_clo_res 989 989 0 0.00 0 4207 20 0 0
v_max_id_ord_sup 5616 5616 0 0.00 0 1755 7 0 0
v_max_non_paid_invoice 1688 1688 0 0.00 0 428 10 0 0
v_max_non_paid_realizn 4006 4006 0 0.00 0 329 8 0 0
v_min_id_clo_ord 5542 5542 0 0.00 0 3183 12 0 0
v_min_id_clo_res 989 989 0 0.00 0 2771 15 0 0
v_min_id_ord_sup 5616 5616 0 0.00 0 4966 11 0 0
v_min_non_paid_invoice 6045 6045 0 0.00 0 6097 17 0 0
v_min_non_paid_realizn 7171 7171 0 0.00 0 12629 19 0 0
v_our_firm 10532 10532 0 0.00 0 83 6 0 0
v_reserve_write_off 20343 20343 0 0.00 0 4915 13 0 0
x_lock_depdocs_on_canc_invoice 10830 10830 0 0.00 0 15063 139 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 4092 412 0 0
x_make_qstorno_1000_1200 5543 5543 0 0.00 3 39728 1183 0 0
x_make_qstorno_1200_2000 5623 5623 0 0.00 3 88271 3228 0 0
x_make_qstorno_2100_3300 461540 461540 0 0.00 0 62049 224 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-03-2023 07:38:34 to 02-03-2023 07:38:38.

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
05:07:30 954 8192000000 8929424912 8985575424 3900160 0.047 2 1 2 0 3997776 61840 35776
05:08:32 31218 8192000000 9736176144 10035953664 810773184 9.005 100 94 186 0 841494144 7965120 22755840
05:10:07 20148 8192000000 9770248000 10076467200 840376624 9.304 100 96 192 0 875766384 12322992 23066768
05:11:38 15526 8192000000 9802188208 10120720384 869702496 9.597 100 88 177 1 911082432 21076768 20303168
05:13:03 5920 8192000000 9883232128 10214223872 926447952 10.160 100 89 178 0 998970688 53129392 19393344
05:14:13 2748 8192000000 9944433200 10288517120 962393808 10.512 100 93 185 1 1061157120 80051056 18712256
05:15:20 2439 8192000000 9961877216 10313351168 991878096 10.800 100 82 161 1 1081530480 73341536 16310848
05:16:26 2080 8192000000 10004257744 10364256256 1017495552 11.048 100 92 177 1 1118293824 83488384 17309888
05:17:30 2449 8192000000 10003184000 10370875392 1039901744 11.264 100 90 173 1 1122421568 65343328 17176496
05:18:34 1137 8192000000 10012845376 10388668416 1060221248 11.459 100 92 172 1 1132997312 54552928 18223136
05:19:38 1376 8192000000 10029146624 10403880960 1065844912 11.512 100 98 189 1 1152348736 68001712 18502112
05:20:41 832 8192000000 10056459728 10434957312 1076091600 11.610 100 99 192 0 1179482976 83982528 19408848
05:21:44 825 8192000000 10070510848 10455994368 1082716016 11.673 100 99 192 2 1191137216 89912208 18508992
05:22:47 1173 8192000000 10054229776 10437869568 1091287344 11.755 100 96 191 2 1183869104 72942528 19639232
05:23:50 1440 8192000000 10058675984 10436018176 1087979328 11.723 100 93 184 2 1175876544 70037456 17859760
05:24:52 1665 8192000000 10054753664 10441105408 1093385072 11.775 100 85 169 1 1179241712 69716544 16140096
05:25:56 1157 8192000000 10073260224 10461585408 1095896016 11.799 100 93 180 2 1196912288 81179744 19836528
05:26:58 1092 8192000000 10075212704 10461388800 1098569360 11.824 100 98 187 0 1190951904 72989584 19392960
05:28:01 1524 8192000000 10069125312 10456354816 1099301136 11.831 100 93 180 3 1193958768 75437200 19220432
05:29:03 1194 8192000000 10066589744 10455797760 1090998832 11.752 100 97 184 0 1183968464 74229504 18740128
05:30:06 833 8192000000 10040557648 10428788736 1095854608 11.798 100 90 168 2 1164900320 50088592 18957120
05:31:09 4045 8192000000 10036326224 10433798144 1089324880 11.736 100 68 128 4 1159096224 56332528 13438816
05:32:13 7585 8192000000 10004032096 10391244800 1057901808 11.436 98 61 110 3 1123537248 52851264 12784176
05:33:22 955 8192000000 10005130544 10381508608 1056996848 11.428 99 85 164 0 1126816880 53271136 16548896
05:34:24 4848 8192000000 9981873472 10354139136 1020520368 11.077 98 70 129 2 1096159872 62783456 12856048
05:35:29 1327 8192000000 9983703600 10360283136 1032228064 11.190 100 97 189 1 1107896512 56474528 19193920
05:36:32 1349 8192000000 9974713168 10343927808 1032100256 11.189 100 91 175 3 1093854112 43352624 18401232
05:37:36 1067 8192000000 9967966288 10337550336 1018078128 11.053 97 81 141 2 1090995504 59668208 13249168
05:38:47 27645 8192000000 10032814176 10417987584 1065337712 11.508 100 67 128 2 1149588688 71751776 12499200
05:40:19 2469 8192000000 10036844688 10421432320 1073245120 11.583 100 71 127 1 1154859136 68997776 12616240
05:41:27 7338 8192000000 10064757600 10451161088 1090809984 11.750 100 78 151 1 1184587584 77428576 16349024
05:42:45 1173 8192000000 10087981440 10470526976 1099849168 11.836 100 92 171 2 1203971680 86216752 17905760
05:43:49 1201 8192000000 10107873008 10494279680 1110979664 11.942 100 92 170 1 1223119216 94394752 17744800
05:44:57 13026 8192000000 10129535904 10525241344 1123840624 12.063 100 71 132 3 1244354544 106350560 14163360
05:46:14 2595 8192000000 10139613184 10529505280 1133768608 12.157 100 84 152 4 1253126816 103958320 15399888
05:47:21 830 8192000000 10178689120 10567909376 1141164304 12.226 100 96 181 0 1289464480 128402208 19897968
05:48:24 1233 8192000000 10216139264 10610237440 1152706896 12.335 100 86 164 1 1328596336 157896224 17993216
05:49:32 10956 8192000000 10228054032 10639515648 1157342208 12.378 100 74 145 1 1342264240 169215856 15706176
05:50:48 2677 8192000000 10257202192 10657869824 1174476416 12.539 100 83 161 2 1367673904 175868304 17329184
05:51:53 1009 8192000000 10290669888 10697449472 1173461248 12.529 100 88 171 0 1408339632 214163456 20714928
05:52:58 1754 8192000000 10310516624 10715340800 1181405360 12.603 100 93 179 1 1421076368 218981904 20689104
05:54:01 1222 8192000000 10334408960 10750324736 1179547472 12.586 100 92 182 0 1439015664 237652560 21815632
05:55:06 693 8192000000 10363884960 10771161088 1183352864 12.621 100 94 182 0 1474329344 270336352 20640128
05:56:10 3510 8192000000 10359530864 10803625984 1173333984 12.528 99 75 144 1 1462347872 271971024 17042864
05:57:19 1096 8192000000 10373937504 10819305472 1170962320 12.506 100 97 184 1 1477170432 283663872 22544240
05:58:23 2685 8192000000 10385252688 10804154368 1181719792 12.606 100 95 188 1 1492200304 289378528 21101984
05:59:29 819 8192000000 10377899232 10824069120 1182274608 12.611 100 98 194 2 1483628928 278543184 22811136
06:00:34 820 8192000000 10386967408 10837667840 1193584448 12.717 100 99 188 2 1492434080 274551120 24298512
06:01:38 969 8192000000 10374601248 10830323712 1191385152 12.696 100 95 186 1 1479061104 266453808 21222144
06:02:43 813 8192000000 10393022272 10804101120 1190113936 12.684 100 94 188 1 1495864304 284509936 21240432
06:03:47 1332 8192000000 10421332208 10837368832 1190231984 12.686 100 72 138 1 1522463904 316298272 15933648
06:04:54 7693 8192000000 10435202672 10870165504 1181935648 12.608 100 53 102 1 1545614512 351173248 12505616
06:06:13 5520 8192000000 10506913312 10940567552 1188921648 12.673 100 54 108 0 1608743824 406245680 13576496
06:07:21 17614 8192000000 10480922400 10909892608 1187031936 12.656 100 44 89 0 1579572704 381789680 10751088
06:08:50 11235 8192000000 10420043424 10886811648 1145625152 12.268 99 41 80 0 1536935520 381075760 10234608
06:10:12 1178 8192000000 10440294672 10907426816 1164604640 12.446 100 83 157 1 1535864624 352819168 18440816
06:11:16 1140 8192000000 10452669088 10889392128 1151059072 12.319 99 96 188 2 1557386384 383088896 23238416
06:12:22 2436 8192000000 10465176832 10929512448 1178447072 12.576 100 72 144 1 1562237536 366392160 17398304
06:13:27 4621 8192000000 10470198656 10911481856 1187885872 12.664 100 57 112 0 1569934800 367730608 14318320
06:14:48 26740 8192000000 10457070608 10923569152 1175655248 12.550 100 38 76 0 1572684704 387575376 9454080
06:16:17 19755 8192000000 10366080864 10879827968 1173417984 12.529 100 44 88 0 1499245344 313532016 12295344
06:17:51 35005 8192000000 10249252800 10777501696 1135503216 12.173 98 22 45 0 1362739040 221355728 5880096
06:19:27 54945 8192000000 10152988576 10612695040 1139467984 12.211 99 38 71 0 1250109776 103944656 6697136
06:21:23 21192 8192000000 10137737184 10650030080 1141342128 12.228 100 25 50 0 1290108528 143215264 5551136
06:22:48 49076 8192000000 10082684624 10536587264 1143411760 12.248 100 24 48 0 1191307280 42203024 5692496
06:24:47 67469 8192000000 10069666320 10535079936 1108073776 11.914 97 29 56 0 1183301056 70176720 5050560
06:26:54 143577 8192000000 10079287744 10586255360 1140082672 12.216 99 13 25 0 1201423008 59037232 2303104
06:30:25 57626 8192000000 10078396528 10537291776 1117076096 11.999 99 29 58 0 1156642544 33009184 6557264
06:32:25 135051 8192000000 10067844800 10553335808 1108780256 11.921 97 6 12 0 1173272272 63233312 1258704
06:35:42 101130 8192000000 10077063792 10543226880 1118610688 12.014 98 15 28 0 1172463232 50842544 3010000
06:38:24 100388 8192000000 10108002480 10556129280 1107238032 11.906 97 32 60 3 1152989120 39783696 5967392
06:41:05 70262 8192000000 10104700560 10594611200 1143668624 12.250 100 48 92 0 1243718160 89916480 10133056
06:43:17 12685 8192000000 10097711056 10542841856 1122535936 12.051 98 72 122 2 1181074192 45918624 12619632
06:44:33 14700 8192000000 10105916480 10544517120 1129194144 12.114 99 28 54 0 1190493040 55444416 5854480
06:45:54 18577 8192000000 10149977264 10592878592 1113142720 11.962 97 41 76 0 1241897456 120831104 7923632
06:47:17 72532 8192000000 10122797488 10596556800 1142208160 12.236 100 12 24 0 1222802384 77962416 2631808
06:49:30 77602 8192000000 10147281216 10625835008 1127677248 12.099 98 26 52 0 1242153200 108031424 6444528
06:51:48 96876 8192000000 10105968240 10582949888 1109555568 11.928 97 12 24 0 1204429056 91572224 3301264
06:54:43 72254 8192000000 10094287520 10564096000 1118809840 12.016 99 14 28 0 1167350096 45942320 2597936
06:57:07 57438 8192000000 10107913024 10609885184 1136756816 12.185 100 9 16 0 1214500816 75852880 1891120
06:59:33 33847 8192000000 10057802192 10569543680 1104700672 11.882 98 23 38 1 1146210576 37423488 4086416
07:01:09 31926 8192000000 10079629568 10535718912 1137770384 12.195 100 38 69 1 1183106576 38285696 7050496
07:02:44 17458 8192000000 10100963776 10547728384 1144129696 12.254 100 36 71 0 1199353104 48380944 6842464
07:04:16 14720 8192000000 10115913904 10554159104 1119402864 12.021 99 34 68 0 1203860448 77727232 6730352
07:05:32 29432 8192000000 10170470928 10638213120 1135000672 12.168 100 42 84 0 1287158912 143247280 8910960
07:07:36 34054 8192000000 10093597696 10563166208 1110302256 11.935 98 31 61 0 1175025632 58700800 6022576
07:09:14 11384 8192000000 10116590832 10542194688 1131042560 12.131 100 55 110 0 1202245328 60161072 11041696
07:10:29 77085 8192000000 10071507328 10561503232 1098580448 11.824 97 12 24 0 1163668944 62861536 2226960
07:12:46 64620 8192000000 10117431776 10561875968 1139095088 12.207 100 32 62 0 1192183824 47001632 6087104
07:14:54 102177 8192000000 10087635776 10566275072 1118386688 12.012 98 37 72 0 1210116784 83570096 8160000
07:17:38 61301 8192000000 10068452784 10537074688 1120076832 12.028 99 7 14 0 1165382048 43911680 1393536
07:19:39 69244 8192000000 10074393184 10525003776 1109781136 11.930 98 21 42 0 1148056144 32871536 5403472
07:21:59 92939 8192000000 10110474976 10575310848 1126180896 12.085 99 21 42 0 1214447904 83571232 4695776
07:25:37 48485 8192000000 10055550176 10500567040 1121528016 12.041 99 30 54 0 1149942576 22936192 5478368
07:27:28 58359 8192000000 10083769408 10537975808 1119076352 12.018 99 30 57 0 1183552928 58382224 6094352
07:29:27 31624 8192000000 10107309248 10557112320 1119133120 12.019 99 26 50 0 1193320080 68937056 5249904
07:31:02 55890 8192000000 10101408416 10561028096 1129017408 12.112 99 17 34 0 1198781072 66120832 3642832
07:33:02 33667 8192000000 10093499312 10534445056 1100253408 11.840 97 36 70 0 1164111856 56809264 7049184
07:34:35 125867 8192000000 10075420368 10515144704 1124014544 12.065 99 42 80 2 1194528576 61131344 9382688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 02-03-2023 07:38:40 to 02-03-2023 07:38:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 155 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-03-2023 07:38:58 to 02-03-2023 07:39:00.

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          220525
MON$OLDEST_ACTIVE               222053
MON$OLDEST_SNAPSHOT             222053
MON$NEXT_TRANSACTION            222053
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                       2118656
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.3.2904 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2904 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2904 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2904 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 ---
:90: 
:91: mscs-test-161.budzdorov.ru    Thu Mar  2 07:37:43 2023
:92:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:93: 
:94: 
:95: mscs-test-161.budzdorov.ru    Thu Mar  2 07:37:44 2023
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:97: 
--- end of diff output ---

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

02.03.23 07:39:01. 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-02 07:39:03 No severe PSQL-related problems occured
02.03.23 07:39:05. End of report.