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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15342736 kB
MemAvailable 30456036 kB
Buffers 0 kB
Cached 16391900 kB
SwapCached 3140 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-20 10:38:02
DTS_END                         2023-02-20 10:37:27.4670
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/57084
TRN_ID                          211585
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: 8651.50 21175 1038180
customer order: creation 158.13 488 18975
customer order: refuse 13.18 422 1582
order to supplier: creation 42.51 1425 5101
order to supplier: removal 3.36 145763 403
invoice (draft): creation 42.03 3648 5044
invoice (draft): removal 48.83 871 5859
invoice accept: apply 38.81 27431 4657
invoice accept: cancel 44.73 1058 5368
customer reserve: creation 3609.98 262 433198
customer reserve: removal 4375.73 88 525087
realization accept: apply 76.84 292 9221
realization accept: cancel 79.31 168 9517
payment from customer: creation 54.64 186 6557
payment from customer: removal 5.24 111 629
payment to supplier: creation 43.35 214 5202
payment to supplier: removal 3.15 139 378
service: total inventory turnovers 3.74 4088 449
service: total monetary turnovers 6.99 415 839
service: aggregate perf. data 0.92 14215 110
service: refresh index statistics 0.03 222225 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-02-2023 10:39:59 to 20-02-2023 10:40:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10184
TEST_TIME 2 8477
TEST_TIME 3 7620
TEST_TIME 4 7750
TEST_TIME 5 7636
TEST_TIME 6 7324
TEST_TIME 7 7304
TEST_TIME 8 7281
TEST_TIME 9 7478
TEST_TIME 10 7272
TEST_TIME 11 7174
TEST_TIME 12 7147
TEST_TIME 13 7259
TEST_TIME 14 7336
TEST_TIME 15 7408
TEST_TIME 16 7476
TEST_TIME 17 7697
TEST_TIME 18 7761
TEST_TIME 19 7918
TEST_TIME 20 8230
TEST_TIME 21 8395
TEST_TIME 22 8464
TEST_TIME 23 8501
TEST_TIME 24 8638
TEST_TIME 25 8896
TEST_TIME 26 8982
TEST_TIME 27 9029
TEST_TIME 28 9293
TEST_TIME 29 9877
TEST_TIME 30 10619
TEST_TIME 31 11046
TEST_TIME 32 10834
TEST_TIME 33 10627
TEST_TIME 34 10446
TEST_TIME 35 10367
TEST_TIME 36 10170
TEST_TIME 37 10100
TEST_TIME 38 10023
TEST_TIME 39 9854
TEST_TIME 40 9751
TEST_TIME 41 9807
TEST_TIME 42 9708
TEST_TIME 43 9649
TEST_TIME 44 9593
TEST_TIME 45 9550
TEST_TIME 46 9434
TEST_TIME 47 9404
TEST_TIME 48 9383
TEST_TIME 49 9331
TEST_TIME 50 9245
TEST_TIME 51 9199
TEST_TIME 52 9107
TEST_TIME 53 9034
TEST_TIME 54 8890
TEST_TIME 55 8814
TEST_TIME 56 8732
TEST_TIME 57 8933
TEST_TIME 58 8981
TEST_TIME 59 8979
TEST_TIME 60 8953
TEST_TIME 61 8928
TEST_TIME 62 8922
TEST_TIME 63 9014
TEST_TIME 64 9099
TEST_TIME 65 9064
TEST_TIME 66 9038
TEST_TIME 67 9063
TEST_TIME 68 9205
TEST_TIME 69 9229
TEST_TIME 70 9306
TEST_TIME 71 9238
TEST_TIME 72 9148
TEST_TIME 73 9055
TEST_TIME 74 9045
TEST_TIME 75 9054
TEST_TIME 76 9000
TEST_TIME 77 8934
TEST_TIME 78 8869
TEST_TIME 79 8917
TEST_TIME 80 8820
TEST_TIME 81 8809
TEST_TIME 82 8756
TEST_TIME 83 8788
TEST_TIME 84 8845
TEST_TIME 85 8848
TEST_TIME 86 8814
TEST_TIME 87 8819
TEST_TIME 88 8832
TEST_TIME 89 8854
TEST_TIME 90 8875
TEST_TIME 91 8909
TEST_TIME 92 8906
TEST_TIME 93 8891
TEST_TIME 94 8985
TEST_TIME 95 9051
TEST_TIME 96 8977
TEST_TIME 97 8911
TEST_TIME 98 8870
TEST_TIME 99 8862
TEST_TIME 100 8831
TEST_TIME 101 8808
TEST_TIME 102 8758
TEST_TIME 103 8715
TEST_TIME 104 8657
TEST_TIME 105 8654
TEST_TIME 106 8707
TEST_TIME 107 8684
TEST_TIME 108 8668
TEST_TIME 109 8662
TEST_TIME 110 8667
TEST_TIME 111 8642
TEST_TIME 112 8684
TEST_TIME 113 8683
TEST_TIME 114 8757
TEST_TIME 115 8745
TEST_TIME 116 8762
TEST_TIME 117 8820
TEST_TIME 118 8775
TEST_TIME 119 8711
TEST_TIME 120 8670

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-02-2023 10:40:02 to 20-02-2023 10:40:06.

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 1437852 1437852 0 0.00 0 24971 13 0 0
doc_list_biud 1437841 1437841 0 0.00 0 63533 38 0 0
sp_add_invoice_to_stock 4737 4657 80 1.68 64 220740 27431 0 80
sp_cancel_adding_invoice 5368 5368 0 0.00 6 111235 1059 0 0
sp_cancel_client_order 1582 1582 0 0.00 6 11703 423 0 0
sp_cancel_customer_reserve 525087 525087 0 0.00 1 33597 89 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 4 1672 111 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 4 5057 140 0 0
sp_cancel_supplier_invoice 5866 5859 7 0.11 3 63586 871 0 7
sp_cancel_supplier_order 403 403 0 0.00 8 1237183 145764 0 0
sp_cancel_write_off 9517 9517 0 0.00 2 15815 168 0 0
sp_client_order 18975 18975 0 0.00 6 36432 488 0 0
sp_customer_reserve 433198 433198 0 0.00 1 69353 262 0 0
sp_fill_shopping_cart 29115 29115 0 0.00 1 26353 93 0 0
sp_fill_shopping_cart_clo_res 433167 433167 0 0.00 0 18101 22 0 0
sp_get_clo_for_invoice 4617 4617 0 0.00 0 34005 376 0 0
sp_kill_cost_storno 15892 15892 0 0.00 0 12746 70 0 0
sp_kill_qstorno_ret_qs2qd 1065953 1065953 0 0.00 0 63529 39 0 0
sp_kill_qty_storno 561652 561652 0 0.00 0 63532 90 0 0
sp_lock_selected_doc 19103 19103 0 0.00 0 4674 6 0 0
sp_make_cost_storno 25595 25595 0 0.00 0 23497 85 0 0
sp_multiply_rows_for_pdistr 25595 25595 0 0.00 0 12133 44 0 0
sp_multiply_rows_for_qdistr 18975 18975 0 0.00 0 26119 176 0 0
sp_pay_from_customer 6557 6557 0 0.00 5 9921 186 0 0
sp_pay_to_supplier 5202 5202 0 0.00 6 16708 214 0 0
sp_payment_common 11759 11759 0 0.00 2 12201 52 0 0
sp_qd_handle_on_cancel_clo 1582 1582 0 0.00 1 11696 343 0 0
sp_qd_handle_on_invoice_upd_sts 9983 9983 0 0.00 0 16685 474 0 0
sp_qd_handle_on_reserve_upd_sts 9221 9221 0 0.00 0 13393 64 0 0
sp_reserve_write_off 9221 9221 0 0.00 8 25041 293 0 0
sp_supplier_invoice 5044 5044 0 0.00 10 111056 3649 0 0
sp_supplier_order 5101 5101 0 0.00 7 42972 1425 0 0
srv_aggregate_perf_data 110 110 0 0.00 2363 140874 14216 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 815 158493 41041 0 0
srv_find_qd_qs_mism 993386 993386 0 0.00 0 17639 10 0 0
srv_make_invnt_saldo 449 449 0 0.00 39 161019 4089 0 0
srv_make_money_saldo 839 839 0 0.00 1 22422 416 0 0
srv_recalc_idx_stat 4 4 0 0.00 36887 395361 222225 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4657 4657 0 0.00 1 16754 898 0 0
t$perf-norm:sp_cancel_adding_invoice 347 347 0 0.00 1 6615 495 0 0
t$perf-norm:sp_cancel_client_order 1582 1582 0 0.00 0 5533 33 0 0
t$perf-norm:sp_cancel_customer_reserve 628 628 0 0.00 0 5348 42 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 713 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 889 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 1468 35 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 84811 5344 0 0
t$perf-norm:sp_cancel_write_off 924 924 0 0.00 0 2706 19 0 0
t$perf-norm:sp_client_order 18975 18975 0 0.00 0 9901 36 0 0
t$perf-norm:sp_customer_reserve 895 895 0 0.00 0 1127 14 0 0
t$perf-norm:sp_pay_from_customer 6557 6557 0 0.00 0 5282 20 0 0
t$perf-norm:sp_pay_to_supplier 5202 5202 0 0.00 0 5440 23 0 0
t$perf-norm:sp_reserve_write_off 9221 9221 0 0.00 0 5410 21 0 0
t$perf-norm:sp_supplier_invoice 5044 5044 0 0.00 0 2919 31 0 0
t$perf-norm:sp_supplier_order 5101 5101 0 0.00 0 2936 31 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 2117 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 436 13 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 1554 14 0 0
t$perf-norm:srv_make_money_saldo 839 839 0 0.00 0 1582 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 204 75 0 0
v_add_invoice_to_stock 9390 9230 160 1.70 0 12804 29 0 160
v_all_customers 34984 34984 0 0.00 0 96 4 0 0
v_all_suppliers 27710 27710 0 0.00 0 97 4 0 0
v_all_wares 37950 37950 0 0.00 0 181 4 0 0
v_cancel_adding_invoice 694 694 0 0.00 0 491 16 0 0
v_cancel_client_order 3164 3164 0 0.00 0 4992 12 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 666 10 0 0
v_cancel_customer_reserve 1256 1256 0 0.00 1 85 9 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 5021 20 0 0
v_cancel_supplier_invoice 766 752 14 1.82 0 1707 26 0 14
v_cancel_supplier_order 806 806 0 0.00 0 652 10 0 0
v_cancel_write_off 1848 1848 0 0.00 0 573 24 0 0
v_max_id_clo_ord 5100 5100 0 0.00 0 2207 6 0 0
v_max_id_clo_res 895 895 0 0.00 0 6310 20 0 0
v_max_id_ord_sup 5040 5040 0 0.00 0 5248 8 0 0
v_max_non_paid_invoice 1487 1487 0 0.00 0 335 8 0 0
v_max_non_paid_realizn 3336 3336 0 0.00 0 2550 9 0 0
v_min_id_clo_ord 5100 5100 0 0.00 0 2950 9 0 0
v_min_id_clo_res 895 895 0 0.00 0 2082 13 0 0
v_min_id_ord_sup 5040 5040 0 0.00 0 9208 16 0 0
v_min_non_paid_invoice 5202 5202 0 0.00 0 12105 24 0 0
v_min_non_paid_realizn 6557 6557 0 0.00 0 7000 21 0 0
v_our_firm 9408 9408 0 0.00 0 96 5 0 0
v_reserve_write_off 18442 18442 0 0.00 0 12344 14 0 0
x_lock_depdocs_on_canc_invoice 10851 10851 0 0.00 0 15202 138 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 7516 354 0 0
x_make_qstorno_1000_1200 5101 5101 0 0.00 3 42945 1263 0 0
x_make_qstorno_1200_2000 5044 5044 0 0.00 4 106369 3369 0 0
x_make_qstorno_2100_3300 433198 433198 0 0.00 0 69339 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-02-2023 10:40:07 to 20-02-2023 10:40:11.

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
08:07:30 2 8192000000 8798265056 8809336832 4344368 0.053 2 1 2 0 4443760 63616 35776
08:08:31 27770 8192000000 9719745792 10021560320 806346944 8.961 100 87 174 0 835642624 8001840 21293840
08:10:04 16894 8192000000 9768669648 10078285824 847276832 9.373 100 84 169 0 879904768 13002864 19625072
08:11:23 15003 8192000000 9796664272 10113875968 863665984 9.537 100 81 160 0 901411168 19148832 18596352
08:12:44 9711 8192000000 9849323328 10181148672 897252576 9.871 100 81 158 0 956225440 41329424 17643440
08:13:55 4896 8192000000 9918121056 10261221376 947712032 10.369 100 88 173 0 1033604960 67444944 18447984
08:15:02 1656 8192000000 9946039136 10295791616 986199232 10.745 100 91 173 1 1075668464 72185920 17283312
08:16:07 1473 8192000000 9997020128 10359627776 1016414544 11.037 100 88 171 0 1118463584 84955040 17094000
08:17:10 1106 8192000000 10000850640 10365976576 1044085856 11.304 100 95 179 1 1118731808 57105504 17540448
08:18:12 969 8192000000 10027067808 10404478976 1061443632 11.470 100 90 170 2 1148873184 70475712 16953840
08:19:14 903 8192000000 10044290224 10425421824 1070011024 11.552 100 95 183 0 1167937056 78377888 19548144
08:20:16 9060 8192000000 10050041568 10434662400 1077574048 11.624 100 64 117 0 1168605680 79644320 11387312
08:21:25 1051 8192000000 10048312496 10432487424 1081662432 11.663 100 94 180 2 1171745824 71061408 19021984
08:22:27 1468 8192000000 10044007200 10431963136 1085510320 11.700 100 78 152 0 1165784064 64657536 15616208
08:23:30 1204 8192000000 10062618288 10450374656 1091236816 11.754 100 98 187 0 1186095568 76757024 18101728
08:24:31 1028 8192000000 10064836512 10456141824 1093516480 11.776 100 94 181 0 1186078816 73684112 18878224
08:25:33 6690 8192000000 10029299424 10424365056 1094164080 11.782 100 66 126 1 1162631712 55118336 13349296
08:26:40 5494 8192000000 10045847088 10440609792 1097198976 11.811 100 69 135 0 1163381328 52075504 14106848
08:27:47 1091 8192000000 10061922144 10453475328 1090703024 11.749 100 98 179 3 1184059872 74929856 18426992
08:28:48 1090 8192000000 10044211952 10426925056 1086030304 11.705 100 95 186 4 1159862128 55967040 17864784
08:29:51 1046 8192000000 10058359904 10442858496 1068563632 11.538 99 86 164 1 1178575824 92800000 17212192
08:30:52 1095 8192000000 10030762144 10406952960 1060484848 11.461 99 95 174 2 1154183824 75369936 18329040
08:31:54 1011 8192000000 10012849600 10392813568 1053829824 11.397 100 88 162 2 1138293984 66559920 17904240
08:32:56 1220 8192000000 10040603088 10417655808 1052556592 11.385 100 88 177 2 1164083184 93708816 17817776
08:33:58 2337 8192000000 9970912624 10350428160 1015594128 11.029 100 64 114 5 1095539888 67197248 12748512
08:35:01 1278 8192000000 9991489664 10368217088 1034260400 11.209 100 82 151 4 1113768256 64153232 15354624
08:36:03 1022 8192000000 9989519616 10362953728 1044206704 11.305 100 92 168 2 1113095984 52042960 16846320
08:37:05 1031 8192000000 10015627856 10396065792 1054361520 11.402 100 87 158 4 1139508992 69579024 15568448
08:38:09 1219 8192000000 10027256816 10407317504 1069303632 11.545 100 94 183 2 1149661920 60736624 19621664
08:39:16 28936 8192000000 10040880800 10426056704 1083755632 11.683 100 75 139 0 1172856720 75271536 13829552
08:40:52 4078 8192000000 10074419072 10462265344 1099424064 11.832 100 82 152 3 1195234368 81155216 14655088
08:42:05 3955 8192000000 10092235440 10485329920 1111868352 11.950 100 75 148 0 1214700416 87624928 15207136
08:43:19 1374 8192000000 10131767296 10535112704 1120467408 12.031 100 78 154 0 1255190400 117347248 17375744
08:44:24 1965 8192000000 10163622416 10568814592 1134919072 12.168 100 88 171 1 1273438160 120645488 17873600
08:45:29 2378 8192000000 10191320352 10589921280 1140124048 12.217 100 85 168 0 1304556048 147101680 17330320
08:46:39 957 8192000000 10175029712 10584903680 1144418016 12.257 100 91 171 0 1288868976 125434800 19016160
08:47:47 1013 8192000000 10224519488 10626039808 1160430368 12.407 100 96 185 0 1342111456 161487744 20193344
08:48:54 4434 8192000000 10261948176 10660818944 1171473104 12.511 100 76 144 0 1370096752 183099280 15524368
08:50:02 1256 8192000000 10277476736 10694402048 1177231952 12.564 100 80 157 0 1389988880 195209856 17547072
08:51:05 968 8192000000 10348735104 10751090688 1186688448 12.653 100 90 172 0 1459357632 252820880 19848304
08:52:08 1880 8192000000 10358483440 10772582400 1180461232 12.594 100 83 163 0 1475096160 275202000 19432928
08:53:15 1500 8192000000 10388226704 10811240448 1187632016 12.661 100 89 177 1 1495642256 288023744 19986496
08:54:20 806 8192000000 10407831056 10833969152 1182489648 12.613 100 97 182 1 1514740048 309785872 22464528
08:55:23 961 8192000000 10422694528 10856783872 1181763104 12.607 100 98 185 1 1532658544 329675600 21219840
08:56:26 1985 8192000000 10445365936 10850562048 1179292304 12.584 100 92 170 1 1551085760 351811744 19981712
08:57:31 6663 8192000000 10426071040 10862997504 1137108448 12.188 96 63 126 0 1502550704 349315888 16126368
08:58:44 1231 8192000000 10444948480 10860539904 1171323744 12.509 100 72 144 0 1555983792 367726720 16933328
08:59:47 1684 8192000000 10460578560 10886926336 1169425488 12.491 100 74 136 2 1567655152 381733760 16495904
09:00:52 3058 8192000000 10439116752 10896048128 1170840624 12.505 99 60 120 0 1545204720 359956912 14407184
09:01:59 3224 8192000000 10485581760 10933665792 1173947552 12.534 100 78 153 1 1598022752 405556848 18518352
09:03:03 13381 8192000000 10459083200 10916941824 1180392464 12.594 100 39 76 0 1585737648 395700688 9644496
09:04:18 19820 8192000000 10485223696 10961936384 1170941776 12.506 100 34 68 0 1607484528 427356912 9185840
09:05:41 17510 8192000000 10404618688 10907504640 1126809936 12.091 98 24 48 0 1512201760 378305632 7086192
09:07:09 93206 8192000000 10079094224 10607976448 1111015472 11.942 97 12 24 0 1227364880 113409424 2939984
09:10:05 64471 8192000000 10070179376 10499125248 1104024640 11.876 97 46 91 1 1164884288 51789744 9069904
09:12:12 86850 8192000000 10101482640 10575572992 1131475376 12.135 99 14 28 0 1196146480 61633216 3037888
09:14:42 28347 8192000000 10095616944 10526302208 1132963568 12.149 99 51 77 0 1192506992 52318976 7224448
09:16:19 106412 8192000000 10128420928 10590978048 1125339568 12.077 98 25 49 0 1223590432 92664432 5586432
09:19:20 39428 8192000000 10104425504 10566356992 1126711392 12.090 99 22 42 0 1199159488 67820192 4627904
09:21:43 40054 8192000000 10055937632 10510024704 1102915360 11.865 97 56 107 2 1144604368 30351344 11337664
09:23:25 73845 8192000000 10094629168 10549415936 1084294048 11.688 96 43 82 0 1155925088 63397216 8233824
09:26:17 33159 8192000000 10038774736 10482470912 1112033168 11.952 99 19 36 0 1149781840 34074096 3674576
09:27:52 55699 8192000000 10073734816 10540036096 1126315936 12.087 100 25 50 0 1197828688 66707856 4804896
09:29:56 75701 8192000000 10092523904 10555224064 1105498320 11.890 98 7 14 0 1169757200 62713488 1545392
09:32:15 75358 8192000000 10128875488 10602127360 1125013008 12.074 99 20 40 0 1238794752 108978032 4803712
09:36:27 44837 8192000000 10074337008 10505814016 1114786000 11.978 98 16 32 2 1136238736 18002352 3450384
09:38:15 36649 8192000000 10119604000 10559016960 1123320352 12.058 98 18 36 0 1199504368 72617504 3566512
09:39:52 61715 8192000000 10115966864 10621624320 1105040864 11.885 97 13 26 0 1185456528 77737984 2677680
09:41:54 70858 8192000000 10139062064 10588217344 1143230752 12.246 100 27 54 0 1212086544 63270848 5584944
09:44:05 72132 8192000000 10152029824 10597122048 1141836272 12.233 100 18 36 0 1260277472 114329888 4111312
09:46:58 158493 8192000000 10080095008 10515574784 1126055376 12.084 98 23 46 0 1171720784 39981856 5683552
09:50:38 29683 8192000000 10115686576 10561667072 1147856288 12.289 100 31 60 0 1218591808 64757200 5978320
09:52:08 65904 8192000000 10040401312 10505687040 1071384976 11.565 94 20 38 1 1105721840 30346704 3990160
09:54:14 74853 8192000000 10092460224 10530725888 1111429504 11.946 98 14 28 0 1157807040 43330720 3046816
09:56:29 109220 8192000000 10091565200 10550669312 1105739136 11.892 98 21 42 0 1161574544 50637984 5197424
09:59:22 90062 8192000000 10112184096 10614153216 1111895056 11.950 98 22 40 0 1179962016 63682768 4384192
10:02:01 57684 8192000000 10053539024 10501910528 1094728672 11.788 97 22 44 2 1173503712 73712928 5062112
10:04:02 29710 8192000000 10119452304 10558156800 1126877200 12.092 99 31 60 0 1187210192 54504848 5828144
10:05:41 22318 8192000000 10100356768 10539671552 1106223488 11.897 98 37 72 2 1186503232 72737536 7542208
10:07:04 39223 8192000000 10101283968 10539974656 1098542128 11.824 97 38 74 0 1163780672 57844096 7394448
10:08:47 36034 8192000000 10082783440 10524377088 1091800240 11.760 97 43 86 1 1149042544 48998496 8243808
10:10:25 22922 8192000000 10166895504 10605850624 1129245040 12.114 100 36 72 0 1266639664 130097216 7297408
10:11:49 48991 8192000000 10106635088 10584547328 1122307984 12.049 99 25 49 0 1229813424 102465504 5039936
10:13:39 87457 8192000000 10082840000 10521337856 1127391696 12.097 99 26 52 0 1170670496 38050336 5228464
10:16:07 75956 8192000000 10075097424 10561867776 1106219808 11.897 98 10 20 0 1181447744 73020512 2207424
10:18:23 62294 8192000000 10037584208 10513506304 1119701136 12.024 100 10 19 0 1156180208 33853472 2625600
10:20:29 112114 8192000000 10112355328 10561810432 1128790320 12.110 99 15 31 0 1217285504 84995792 3499392
10:23:22 60754 8192000000 10060551408 10514169856 1104039008 11.876 97 31 58 0 1139365552 29303392 6023152
10:25:23 38898 8192000000 10088024592 10539581440 1127573888 12.098 99 26 53 0 1184968736 50972464 6422384
10:27:13 47331 8192000000 10127711728 10569322496 1127928432 12.102 99 35 70 0 1215014544 79532992 7553120
10:29:15 51607 8192000000 10143433376 10618941440 1123133248 12.057 99 26 49 0 1253969264 125434944 5401072
10:31:07 48108 8192000000 10103329264 10573955072 1137819184 12.195 100 19 38 0 1219357168 77518608 4019376
10:32:57 36065 8192000000 10150353824 10609303552 1145796176 12.270 100 39 78 2 1249763392 96250544 7716672
10:34:37 117837 8192000000 10115842032 10554617856 1107525392 11.909 97 44 86 0 1157689920 41888592 8275936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 20-02-2023 10:40:13 to 20-02-2023 10:40:29.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-02-2023 10:40:30 to 20-02-2023 10:40:32.

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          215002
MON$OLDEST_ACTIVE               218147
MON$OLDEST_SNAPSHOT             218147
MON$NEXT_TRANSACTION            218147
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                       2032928
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             758
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 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 ---
:51: 
:52: mscs-test-161.budzdorov.ru    Mon Feb 20 10:38:04 2023
:53:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:54: 
:55: 
:56: mscs-test-161.budzdorov.ru    Mon Feb 20 10:38:04 2023
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:58: 
:59: 
:60: mscs-test-161.budzdorov.ru    Mon Feb 20 10:38:04 2023
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:62: 
--- end of diff output ---

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

20.02.23 10:40:33. 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-20 10:40:36 No severe PSQL-related problems occured
20.02.23 10:40:37. End of report.