Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.12.2021 10: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 16678324 kB
MemAvailable 31423132 kB
Buffers 0 kB
Cached 15050580 kB
SwapCached 436 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.1
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 2021-12-27 10:37:10
DTS_END                         2021-12-27 10:37:10.5670
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/36932
TRN_ID                          232962
ATT_ID                          650
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: 8777.38 21835 1053285
customer order: creation 167.15 445 20058
customer order: refuse 14.63 325 1755
order to supplier: creation 45.14 1530 5417
order to supplier: removal 3.13 115892 376
invoice (draft): creation 44.19 3546 5303
invoice (draft): removal 47.93 959 5751
invoice accept: apply 40.10 18515 4812
invoice accept: cancel 43.22 828 5186
customer reserve: creation 3828.56 177 459427
customer reserve: removal 4258.29 67 510995
realization accept: apply 82.20 186 9864
realization accept: cancel 76.05 117 9126
payment from customer: creation 58.78 116 7054
payment from customer: removal 5.67 107 680
payment to supplier: creation 47.57 135 5708
payment to supplier: removal 3.43 99 412
service: total inventory turnovers 4.05 2987 486
service: total monetary turnovers 6.39 458 767
service: aggregate perf. data 0.87 18422 104
service: refresh index statistics 0.03 271796 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-12-2021 10:38:40 to 27-12-2021 10:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6980
TEST_TIME 2 5232
TEST_TIME 3 4892
TEST_TIME 4 4502
TEST_TIME 5 5029
TEST_TIME 6 4992
TEST_TIME 7 4982
TEST_TIME 8 5247
TEST_TIME 9 5552
TEST_TIME 10 5752
TEST_TIME 11 5916
TEST_TIME 12 6769
TEST_TIME 13 6729
TEST_TIME 14 7132
TEST_TIME 15 7750
TEST_TIME 16 8322
TEST_TIME 17 8466
TEST_TIME 18 8665
TEST_TIME 19 8834
TEST_TIME 20 8987
TEST_TIME 21 9588
TEST_TIME 22 9775
TEST_TIME 23 9752
TEST_TIME 24 10476
TEST_TIME 25 11173
TEST_TIME 26 11531
TEST_TIME 27 11335
TEST_TIME 28 10962
TEST_TIME 29 11091
TEST_TIME 30 10898
TEST_TIME 31 10848
TEST_TIME 32 10780
TEST_TIME 33 10579
TEST_TIME 34 10545
TEST_TIME 35 10383
TEST_TIME 36 10324
TEST_TIME 37 10384
TEST_TIME 38 10143
TEST_TIME 39 9980
TEST_TIME 40 9940
TEST_TIME 41 9785
TEST_TIME 42 9623
TEST_TIME 43 9637
TEST_TIME 44 9716
TEST_TIME 45 9589
TEST_TIME 46 9506
TEST_TIME 47 9568
TEST_TIME 48 9633
TEST_TIME 49 9794
TEST_TIME 50 9725
TEST_TIME 51 9721
TEST_TIME 52 9707
TEST_TIME 53 9856
TEST_TIME 54 9805
TEST_TIME 55 9801
TEST_TIME 56 9643
TEST_TIME 57 9607
TEST_TIME 58 9622
TEST_TIME 59 9554
TEST_TIME 60 9457
TEST_TIME 61 9401
TEST_TIME 62 9390
TEST_TIME 63 9357
TEST_TIME 64 9316
TEST_TIME 65 9304
TEST_TIME 66 9344
TEST_TIME 67 9436
TEST_TIME 68 9412
TEST_TIME 69 9414
TEST_TIME 70 9415
TEST_TIME 71 9376
TEST_TIME 72 9401
TEST_TIME 73 9452
TEST_TIME 74 9404
TEST_TIME 75 9458
TEST_TIME 76 9453
TEST_TIME 77 9335
TEST_TIME 78 9318
TEST_TIME 79 9269
TEST_TIME 80 9204
TEST_TIME 81 9116
TEST_TIME 82 9115
TEST_TIME 83 9073
TEST_TIME 84 9072
TEST_TIME 85 9052
TEST_TIME 86 9101
TEST_TIME 87 9123
TEST_TIME 88 9092
TEST_TIME 89 9171
TEST_TIME 90 9153
TEST_TIME 91 9173
TEST_TIME 92 9190
TEST_TIME 93 9170
TEST_TIME 94 9200
TEST_TIME 95 9205
TEST_TIME 96 9169
TEST_TIME 97 9082
TEST_TIME 98 9083
TEST_TIME 99 9045
TEST_TIME 100 9031
TEST_TIME 101 9045
TEST_TIME 102 9022
TEST_TIME 103 8989
TEST_TIME 104 8965
TEST_TIME 105 8936
TEST_TIME 106 9039
TEST_TIME 107 9007
TEST_TIME 108 9014
TEST_TIME 109 9046
TEST_TIME 110 9048
TEST_TIME 111 9026
TEST_TIME 112 9046
TEST_TIME 113 9014
TEST_TIME 114 8945
TEST_TIME 115 8937
TEST_TIME 116 8910
TEST_TIME 117 8893
TEST_TIME 118 8886
TEST_TIME 119 8857
TEST_TIME 120 8786

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-12-2021 10:38:44 to 27-12-2021 10:38:47.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1476583 1476583 0 0.00 0 24140 6 0 0
doc_list_biud 1476580 1476580 0 0.00 0 73950 30 0 0
sp_add_invoice_to_stock 4939 4812 127 2.57 20 251709 18516 0 127
sp_cancel_adding_invoice 5186 5186 0 0.00 5 79857 829 0 0
sp_cancel_client_order 1755 1755 0 0.00 4 17987 325 0 0
sp_cancel_customer_reserve 510995 510995 0 0.00 1 28352 67 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 10574 107 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 7330 100 0 0
sp_cancel_supplier_invoice 5756 5751 5 0.08 3 73952 959 0 5
sp_cancel_supplier_order 376 376 0 0.00 9 1177047 115892 0 0
sp_cancel_write_off 9126 9126 0 0.00 1 18877 117 0 0
sp_client_order 20058 20058 0 0.00 4 27086 446 0 0
sp_customer_reserve 459427 459427 0 0.00 0 147974 177 0 0
sp_fill_shopping_cart 30761 30761 0 0.00 1 20029 44 0 0
sp_fill_shopping_cart_clo_res 459415 459415 0 0.00 0 18199 17 0 0
sp_get_clo_for_invoice 4800 4800 0 0.00 0 28613 400 0 0
sp_kill_cost_storno 15404 15404 0 0.00 0 24127 62 0 0
sp_kill_qstorno_ret_qs2qd 1037243 1037243 0 0.00 0 73949 37 0 0
sp_kill_qty_storno 547853 547853 0 0.00 0 73950 79 0 0
sp_lock_selected_doc 20378 20378 0 0.00 0 6975 5 0 0
sp_make_cost_storno 27426 27426 0 0.00 0 16227 57 0 0
sp_multiply_rows_for_pdistr 27426 27426 0 0.00 0 14866 32 0 0
sp_multiply_rows_for_qdistr 20058 20058 0 0.00 0 15503 231 0 0
sp_pay_from_customer 7054 7054 0 0.00 4 16107 117 0 0
sp_pay_to_supplier 5708 5708 0 0.00 5 12721 136 0 0
sp_payment_common 12762 12762 0 0.00 1 8514 20 0 0
sp_qd_handle_on_cancel_clo 1755 1755 0 0.00 0 17979 292 0 0
sp_qd_handle_on_invoice_upd_sts 9986 9986 0 0.00 0 29179 361 0 0
sp_qd_handle_on_reserve_upd_sts 9864 9864 0 0.00 0 25554 61 0 0
sp_reserve_write_off 9864 9864 0 0.00 7 25587 186 0 0
sp_supplier_invoice 5303 5303 0 0.00 6 103397 3547 0 0
sp_supplier_order 5417 5417 0 0.00 5 38640 1531 0 0
srv_aggregate_perf_data 104 104 0 0.00 2574 147911 18423 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1292 177601 48330 0 0
srv_find_qd_qs_mism 1005301 1005301 0 0.00 0 17847 5 0 0
srv_make_invnt_saldo 486 486 0 0.00 36 109048 2987 0 0
srv_make_money_saldo 767 767 0 0.00 1 28340 458 0 0
srv_recalc_idx_stat 4 4 0 0.00 186031 384973 271797 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4812 4812 0 0.00 0 37270 175 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 16721 153 0 0
t$perf-norm:sp_cancel_client_order 1755 1755 0 0.00 0 3921 16 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 3106 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 684 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 444 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 1400 11 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 21462 1645 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 314 2 0 0
t$perf-norm:sp_client_order 20058 20058 0 0.00 0 25534 21 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 5259 10 0 0
t$perf-norm:sp_pay_from_customer 7054 7054 0 0.00 0 4647 9 0 0
t$perf-norm:sp_pay_to_supplier 5708 5708 0 0.00 0 9185 13 0 0
t$perf-norm:sp_reserve_write_off 9864 9864 0 0.00 0 10814 11 0 0
t$perf-norm:sp_supplier_invoice 5303 5303 0 0.00 0 14960 27 0 0
t$perf-norm:sp_supplier_order 5417 5417 0 0.00 0 21576 18 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 583 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 2876 21 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 360 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 1 0 0
v_add_invoice_to_stock 9850 9596 254 2.57 0 7365 15 0 254
v_all_customers 36902 36902 0 0.00 0 24 1 0 0
v_all_suppliers 29992 29992 0 0.00 0 104 1 0 0
v_all_wares 40116 40116 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 2514 12 0 0
v_cancel_client_order 3510 3510 0 0.00 0 6762 11 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 10275 14 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 2425 8 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 2591 13 0 0
v_cancel_supplier_invoice 826 816 10 1.21 0 270 11 0 10
v_cancel_supplier_order 752 752 0 0.00 0 1499 7 0 0
v_cancel_write_off 1948 1948 0 0.00 0 2312 15 0 0
v_max_id_clo_ord 5407 5407 0 0.00 0 1005 1 0 0
v_max_id_clo_res 939 939 0 0.00 0 3938 20 0 0
v_max_id_ord_sup 5295 5295 0 0.00 0 7442 6 0 0
v_max_non_paid_invoice 1414 1414 0 0.00 0 877 3 0 0
v_max_non_paid_realizn 3622 3622 0 0.00 0 628 2 0 0
v_min_id_clo_ord 5407 5407 0 0.00 0 7821 15 0 0
v_min_id_clo_res 939 939 0 0.00 0 8425 18 0 0
v_min_id_ord_sup 5295 5295 0 0.00 0 12900 10 0 0
v_min_non_paid_invoice 5708 5708 0 0.00 0 7207 15 0 0
v_min_non_paid_realizn 7054 7054 0 0.00 0 4539 12 0 0
v_our_firm 10078 10078 0 0.00 0 17 1 0 0
v_reserve_write_off 19728 19728 0 0.00 0 10325 7 0 0
x_lock_depdocs_on_canc_invoice 10529 10529 0 0.00 0 22996 106 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 34720 449 0 0
x_make_qstorno_1000_1200 5417 5417 0 0.00 1 38629 1449 0 0
x_make_qstorno_1200_2000 5303 5303 0 0.00 3 103390 3380 0 0
x_make_qstorno_2100_3300 459427 459427 0 0.00 0 147972 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-12-2021 10:38:48 to 27-12-2021 10:38:52.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:14 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:15 23922 8192000000 9738811456 10043494400 816586128 9.064 100 87 174 1 845692896 7750144 21356624
08:09:47 19783 8192000000 9779695344 10090983424 853189648 9.432 100 88 176 2 887519168 13494992 20834528
08:11:11 5537 8192000000 9802836880 10122498048 873061392 9.631 100 84 168 0 915648944 23323040 19264512
08:12:26 11537 8192000000 9856146928 10187943936 905107280 9.949 100 75 151 0 960636944 38608240 16921424
08:13:43 11102 8192000000 9902909632 10250342400 949754256 10.389 100 83 167 0 1023217776 55893552 17569968
08:14:56 5436 8192000000 9952569216 10304970752 986382064 10.746 100 84 168 1 1067028144 63779472 16866608
08:16:03 2842 8192000000 10000875408 10361040896 1012867808 11.003 100 88 174 2 1112304960 81838224 17598928
08:17:09 2442 8192000000 10011795456 10375585792 1043676976 11.300 100 81 161 0 1112898368 53292768 15928624
08:18:13 1710 8192000000 10043563328 10412285952 1060092032 11.457 100 92 183 1 1150685808 71151056 19442720
08:19:16 33585 8192000000 9987323600 10370928640 1069406128 11.546 100 64 127 1 1098621328 13327616 15887584
08:20:49 8834 8192000000 9994483536 10387484672 1078671984 11.635 100 47 93 2 1105905520 17298976 9934560
08:22:01 6424 8192000000 9986361056 10393149440 1086398640 11.708 100 27 54 2 1117154432 24967168 5788624
08:23:08 6501 8192000000 10000261408 10395820032 1095724352 11.797 100 35 67 4 1115891392 13403200 6763840
08:24:15 66265 8192000000 10010825952 10420768768 1099746064 11.835 100 26 50 2 1136159552 29697920 6715568
08:26:22 5220 8192000000 10011091184 10417491968 1099941856 11.837 100 40 78 2 1133422512 24699856 8780800
08:27:30 9685 8192000000 10007327856 10415833088 1085361200 11.699 99 30 60 2 1115797104 23959040 6476864
08:28:42 11659 8192000000 9988857376 10380881920 1068225824 11.535 100 32 66 0 1096988928 21607888 7155216
08:29:58 32088 8192000000 9965830928 10371129344 995812304 10.838 93 32 65 0 1023045312 20396544 6836464
08:31:34 6117 8192000000 9934080896 10320203776 1016420672 11.037 99 28 54 1 1045978928 23353072 6205184
08:32:41 5283 8192000000 9953280496 10351448064 1043438400 11.298 100 31 62 0 1072903696 22737424 6727872
08:33:47 7006 8192000000 9964170080 10351058944 1050145968 11.362 100 43 85 1 1079536752 20592096 8798688
08:34:56 26650 8192000000 9976205536 10381787136 1056521392 11.423 100 35 68 2 1087243568 22642208 8079968
08:36:25 5322 8192000000 9978819872 10371178496 1062965168 11.485 100 25 51 0 1088255520 19435008 5855344
08:37:32 19411 8192000000 10002621760 10396434432 1090212832 11.745 100 50 99 2 1112918944 12365184 10340928
08:38:58 99560 8192000000 10067480880 10476036096 1112364560 11.955 100 34 68 0 1199190464 80156656 6669248
08:41:46 33898 8192000000 10136794720 10545528832 1129382688 12.116 100 51 102 0 1241447600 100971184 11093728
08:43:29 7154 8192000000 10179118688 10585415680 1150687216 12.316 100 52 102 1 1281913136 119149120 12076800
08:44:42 14704 8192000000 10230915776 10639147008 1159064800 12.395 100 63 123 1 1331150496 157597968 14487728
08:46:00 2788 8192000000 10262528736 10668433408 1169884080 12.496 100 72 144 0 1363529152 178004000 15641072
08:47:07 1874 8192000000 10295746528 10690973696 1172106896 12.517 100 77 151 2 1391146752 202438592 16601264
08:48:12 1291 8192000000 10363769392 10767044608 1172332560 12.519 100 85 169 0 1467181920 275753040 19096320
08:49:18 3304 8192000000 10403419168 10817794048 1180001728 12.590 100 84 168 1 1500313856 301035936 19276192
08:50:25 15071 8192000000 10414757408 10830966784 1176936656 12.562 100 66 131 1 1517285136 323928560 16419920
08:51:55 1756 8192000000 10406710528 10814148608 1172468576 12.520 100 76 151 1 1510322208 319843376 18010256
08:53:01 3307 8192000000 10370025360 10803953664 1157856560 12.383 99 80 159 0 1464359600 288167840 18335200
08:54:06 18541 8192000000 10318418192 10741084160 1133804288 12.157 98 38 77 0 1411026128 267742224 9479616
08:55:39 6861 8192000000 10342437744 10760998912 1145091456 12.263 100 40 79 0 1437238400 282672832 9474112
08:56:50 16189 8192000000 10377812496 10829156352 1140130864 12.217 98 38 76 0 1475814336 325724544 9958928
08:58:19 10028 8192000000 10385654352 10814201856 1132679856 12.147 98 41 82 1 1480361168 337003280 10678032
08:59:30 24035 8192000000 10301327008 10724433920 1119206656 12.019 98 25 50 1 1383727408 258202944 6317808
09:00:56 28060 8192000000 10301415680 10828496896 1129381952 12.116 100 25 50 1 1470309792 334230448 6697392
09:02:34 15561 8192000000 10171121200 10595926016 1110456640 11.937 99 20 40 0 1296101456 181185056 4459760
09:03:55 86523 8192000000 10110321088 10545201152 1108569744 11.919 99 31 61 0 1202518720 86862496 7086480
09:06:36 125598 8192000000 10087621216 10561306624 1117719760 12.005 100 18 37 0 1224796496 103191808 3884928
09:10:28 20375 8192000000 10091552800 10544635904 1132781792 12.148 100 16 32 1 1202791424 66802048 3207584
09:11:49 43840 8192000000 10101412880 10547347456 1140080048 12.216 100 23 46 1 1214963264 70164816 4718400
09:13:33 54469 8192000000 10056883584 10567315456 1121134480 12.038 99 12 24 0 1197676432 74026368 2515584
09:15:40 71445 8192000000 10085536208 10527260672 1119929232 12.026 99 31 62 0 1165064256 38362480 6772544
09:18:03 44947 8192000000 10089520800 10537660416 1132711328 12.147 100 14 29 0 1191319568 55318560 3289680
09:19:51 58282 8192000000 10119212816 10602291200 1129381184 12.116 99 20 40 0 1231874976 97873904 4619888
09:21:50 41833 8192000000 10061030512 10495799296 1119015344 12.018 99 14 28 0 1158591184 36755744 2820096
09:23:37 49729 8192000000 10115281808 10579247104 1135834352 12.176 100 25 49 1 1224003824 82735344 5434128
09:25:38 70469 8192000000 10077979792 10542841856 1145112528 12.264 100 17 34 0 1197891248 48966912 3811808
09:27:53 30714 8192000000 10104880400 10539040768 1135953760 12.177 99 51 101 1 1203568192 56814512 10799920
09:29:28 31408 8192000000 10148830336 10616184832 1141171872 12.227 99 40 81 1 1237865584 88539136 8154576
09:31:05 149097 8192000000 10078953280 10542886912 1087038912 11.714 95 27 52 2 1130102144 37353568 5709664
09:34:41 91526 8192000000 10092600320 10571096064 1143509120 12.249 100 24 48 0 1224482896 75776848 5196928
09:37:26 57476 8192000000 10083239104 10554912768 1136256880 12.180 99 10 20 0 1192914384 54574912 2082592
09:39:34 54570 8192000000 10070342800 10542465024 1130864640 12.130 99 6 12 0 1173355296 41435664 1054992
09:41:33 39446 8192000000 10101609440 10542444544 1124669520 12.071 98 33 63 0 1165541888 34760512 6111856
09:43:19 60089 8192000000 10131987616 10607144960 1119210528 12.020 98 12 24 0 1202845920 80918736 2716656
09:45:31 61863 8192000000 10043193056 10481000448 1067014752 11.524 94 17 33 0 1110517280 40269504 3233024
09:47:33 54417 8192000000 10079837072 10519326720 1082237504 11.669 97 23 46 0 1136060928 49285584 4537840
09:49:53 23529 8192000000 10084246144 10505056256 1105184864 11.887 99 46 92 0 1167020432 51021472 10814096
09:51:17 22235 8192000000 10081783920 10502610944 1111640048 11.948 99 33 66 0 1156374736 38307440 6427248
09:52:44 50037 8192000000 10067487680 10540265472 1099702272 11.835 98 6 12 0 1159528224 58718224 1107728
09:55:22 46394 8192000000 10045578800 10488688640 1114114304 11.971 99 13 26 1 1143795504 26863648 2817552
09:57:18 52559 8192000000 10054254224 10503806976 1100215360 11.840 98 10 20 0 1141851216 38612064 3023792
09:59:11 51666 8192000000 10063020240 10536431616 1115791936 11.987 99 6 12 0 1155709680 38919936 997808
10:01:14 58002 8192000000 10069078976 10503897088 1106064912 11.895 98 22 44 2 1169399952 58530608 4804432
10:03:14 57054 8192000000 10072460624 10508574720 1087391936 11.718 97 22 45 0 1158218464 65512896 5313632
10:05:18 78805 8192000000 10097856672 10550673408 1112389632 11.955 99 23 46 2 1195022896 77639632 4993632
10:07:44 51348 8192000000 10082040272 10524303360 1118782880 12.015 99 22 45 1 1172150464 49024944 4342640
10:09:42 52813 8192000000 10113468896 10554646528 1107770560 11.911 98 39 76 1 1169769936 53841168 8158208
10:12:35 177600 8192000000 10080146224 10559246336 1116580528 11.995 98 20 40 0 1194799488 74284112 3934848
10:16:35 56386 8192000000 10061888016 10534895616 1128852832 12.111 99 11 22 0 1184580592 52810000 2917760
10:18:33 69787 8192000000 10073846832 10540544000 1129004944 12.112 99 16 32 0 1180338496 47654720 3678832
10:20:54 48638 8192000000 10117742096 10567974912 1150943392 12.318 100 24 49 0 1214684128 58694656 5046080
10:22:46 88374 8192000000 10086087456 10534621184 1112383648 11.955 97 15 30 0 1191458720 75974512 3100560
10:25:30 66975 8192000000 10084694864 10585554944 1109746768 11.930 97 16 32 0 1179246496 66422192 3077536
10:27:37 80127 8192000000 10072787296 10497515520 1078279376 11.631 95 25 50 1 1132571488 48495232 5796880
10:29:58 33955 8192000000 10095998416 10534547456 1125747424 12.081 100 33 66 1 1182990016 50514576 6728016
10:31:33 75731 8192000000 10062664720 10516746240 1107342704 11.907 98 25 50 0 1147726368 35099984 5283680
10:33:49 98985 8192000000 10032751616 10515136512 1104673072 11.882 98 5 10 0 1160605216 54814656 1117488
10:36:33 33721 8192000000 10090593216 10531373056 1129381504 12.116 100 27 53 1 1187337248 52014320 5941424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-12-2021 10:38:54 to 27-12-2021 10:39:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-12-2021 10:39:10 to 27-12-2021 10:39:12.

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          231112
MON$OLDEST_ACTIVE               233458
MON$OLDEST_SNAPSHOT             233458
MON$NEXT_TRANSACTION            233458
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                       2027488
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2696 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2696 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2696 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2696 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 ---
:255: 
:256: mscs-test-161.budzdorov.ru    Mon Dec 27 10:37:13 2021
:257:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:258: 
--- end of diff output ---

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

27.12.21 10:39:13. 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
2021-12-27 10:39:15 No severe PSQL-related problems occured
27.12.21 10:39:17. End of report.