Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.07.2022 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 15997736 kB
MemAvailable 31093344 kB
Buffers 0 kB
Cached 15740356 kB
SwapCached 2492 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-20 10:37:17
DTS_END                         2022-07-20 10:37:17.2260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46306
TRN_ID                          206989
ATT_ID                          604
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: 8591.65 22685 1030998
customer order: creation 156.87 539 18824
customer order: refuse 13.43 415 1612
order to supplier: creation 40.70 1558 4884
order to supplier: removal 3.22 166261 386
invoice (draft): creation 41.41 3498 4969
invoice (draft): removal 48.51 1021 5821
invoice accept: apply 37.68 26105 4522
invoice accept: cancel 43.83 1162 5260
customer reserve: creation 3626.48 240 435178
customer reserve: removal 4317.02 98 518043
realization accept: apply 76.42 262 9170
realization accept: cancel 69.57 163 8348
payment from customer: creation 53.09 188 6371
payment from customer: removal 4.98 142 598
payment to supplier: creation 44.63 204 5356
payment to supplier: removal 2.95 109 354
service: total inventory turnovers 3.50 3930 420
service: total monetary turnovers 6.58 331 790
service: aggregate perf. data 0.73 16941 87
service: refresh index statistics 0.04 230532 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-07-2022 10:39:24 to 20-07-2022 10:39:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5279
TEST_TIME 2 5316
TEST_TIME 3 5374
TEST_TIME 4 5595
TEST_TIME 5 5306
TEST_TIME 6 5205
TEST_TIME 7 5269
TEST_TIME 8 5364
TEST_TIME 9 5408
TEST_TIME 10 5366
TEST_TIME 11 5980
TEST_TIME 12 6483
TEST_TIME 13 6623
TEST_TIME 14 6666
TEST_TIME 15 6779
TEST_TIME 16 6960
TEST_TIME 17 7183
TEST_TIME 18 7203
TEST_TIME 19 7448
TEST_TIME 20 7816
TEST_TIME 21 8029
TEST_TIME 22 8235
TEST_TIME 23 8185
TEST_TIME 24 8488
TEST_TIME 25 8468
TEST_TIME 26 8829
TEST_TIME 27 9615
TEST_TIME 28 10068
TEST_TIME 29 10530
TEST_TIME 30 10551
TEST_TIME 31 10505
TEST_TIME 32 10347
TEST_TIME 33 10314
TEST_TIME 34 10078
TEST_TIME 35 9979
TEST_TIME 36 9993
TEST_TIME 37 9912
TEST_TIME 38 9817
TEST_TIME 39 9756
TEST_TIME 40 9545
TEST_TIME 41 9483
TEST_TIME 42 9571
TEST_TIME 43 9451
TEST_TIME 44 9279
TEST_TIME 45 9255
TEST_TIME 46 9168
TEST_TIME 47 9128
TEST_TIME 48 9147
TEST_TIME 49 8995
TEST_TIME 50 8966
TEST_TIME 51 8907
TEST_TIME 52 8852
TEST_TIME 53 8940
TEST_TIME 54 8908
TEST_TIME 55 8927
TEST_TIME 56 8959
TEST_TIME 57 8940
TEST_TIME 58 8943
TEST_TIME 59 8941
TEST_TIME 60 8882
TEST_TIME 61 8930
TEST_TIME 62 8977
TEST_TIME 63 8926
TEST_TIME 64 8845
TEST_TIME 65 9126
TEST_TIME 66 9094
TEST_TIME 67 8991
TEST_TIME 68 8936
TEST_TIME 69 8877
TEST_TIME 70 8854
TEST_TIME 71 8831
TEST_TIME 72 8799
TEST_TIME 73 8756
TEST_TIME 74 8723
TEST_TIME 75 8689
TEST_TIME 76 8620
TEST_TIME 77 8667
TEST_TIME 78 8657
TEST_TIME 79 8659
TEST_TIME 80 8611
TEST_TIME 81 8673
TEST_TIME 82 8718
TEST_TIME 83 8675
TEST_TIME 84 8661
TEST_TIME 85 8661
TEST_TIME 86 8749
TEST_TIME 87 8737
TEST_TIME 88 8809
TEST_TIME 89 8875
TEST_TIME 90 8880
TEST_TIME 91 8808
TEST_TIME 92 8786
TEST_TIME 93 8734
TEST_TIME 94 8747
TEST_TIME 95 8699
TEST_TIME 96 8645
TEST_TIME 97 8603
TEST_TIME 98 8555
TEST_TIME 99 8512
TEST_TIME 100 8482
TEST_TIME 101 8547
TEST_TIME 102 8573
TEST_TIME 103 8612
TEST_TIME 104 8670
TEST_TIME 105 8681
TEST_TIME 106 8643
TEST_TIME 107 8680
TEST_TIME 108 8789
TEST_TIME 109 8839
TEST_TIME 110 8865
TEST_TIME 111 8825
TEST_TIME 112 8764
TEST_TIME 113 8751
TEST_TIME 114 8780
TEST_TIME 115 8769
TEST_TIME 116 8747
TEST_TIME 117 8718
TEST_TIME 118 8694
TEST_TIME 119 8662
TEST_TIME 120 8598

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-07-2022 10:39:28 to 20-07-2022 10:39:31.

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 1431456 1431456 0 0.00 0 30591 12 0 0
doc_list_biud 1431452 1431452 0 0.00 0 75321 41 0 0
sp_add_invoice_to_stock 4638 4522 116 2.50 24 232946 26106 0 116
sp_cancel_adding_invoice 5260 5260 0 0.00 6 107105 1162 0 0
sp_cancel_client_order 1612 1612 0 0.00 6 16703 416 0 0
sp_cancel_customer_reserve 518043 518043 0 0.00 1 75366 98 0 0
sp_cancel_pay_from_customer 598 598 0 0.00 3 6239 142 0 0
sp_cancel_pay_to_supplier 354 354 0 0.00 4 1861 110 0 0
sp_cancel_supplier_invoice 5837 5821 16 0.27 2 18107 1021 0 16
sp_cancel_supplier_order 386 386 0 0.00 8 1315529 166261 0 0
sp_cancel_write_off 8348 8348 0 0.00 2 13792 164 0 0
sp_client_order 18824 18824 0 0.00 5 31157 540 0 0
sp_customer_reserve 435178 435178 0 0.00 0 43458 240 0 0
sp_fill_shopping_cart 28663 28663 0 0.00 1 33678 89 0 0
sp_fill_shopping_cart_clo_res 435171 435171 0 0.00 0 15234 21 0 0
sp_get_clo_for_invoice 4515 4515 0 0.00 0 25418 416 0 0
sp_kill_cost_storno 14560 14560 0 0.00 0 30588 78 0 0
sp_kill_qstorno_ret_qs2qd 1050641 1050641 0 0.00 0 75216 44 0 0
sp_kill_qty_storno 553153 553153 0 0.00 0 75318 99 0 0
sp_lock_selected_doc 18816 18816 0 0.00 0 7211 7 0 0
sp_make_cost_storno 25412 25412 0 0.00 0 15901 79 0 0
sp_multiply_rows_for_pdistr 25411 25411 0 0.00 0 13255 40 0 0
sp_multiply_rows_for_qdistr 18824 18824 0 0.00 0 15096 195 0 0
sp_pay_from_customer 6371 6371 0 0.00 6 13346 189 0 0
sp_pay_to_supplier 5356 5356 0 0.00 5 16282 204 0 0
sp_payment_common 11727 11727 0 0.00 2 13238 50 0 0
sp_qd_handle_on_cancel_clo 1612 1612 0 0.00 0 16695 332 0 0
sp_qd_handle_on_invoice_upd_sts 9774 9774 0 0.00 0 29421 482 0 0
sp_qd_handle_on_reserve_upd_sts 9169 9169 0 0.00 0 20765 59 0 0
sp_reserve_write_off 9170 9170 0 0.00 7 20813 262 0 0
sp_supplier_invoice 4969 4969 0 0.00 10 102665 3498 0 0
sp_supplier_order 4884 4884 0 0.00 7 41471 1559 0 0
srv_aggregate_perf_data 87 87 0 0.00 11 78467 16941 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 867 131052 41855 0 0
srv_find_qd_qs_mism 985972 985972 0 0.00 0 7941 9 0 0
srv_make_invnt_saldo 420 420 0 0.00 58 60261 3931 0 0
srv_make_money_saldo 790 790 0 0.00 1 9029 331 0 0
srv_recalc_idx_stat 5 5 0 0.00 90348 353476 230533 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4523 4523 0 0.00 1 9071 830 0 0
t$perf-norm:sp_cancel_adding_invoice 351 351 0 0.00 1 10280 488 0 0
t$perf-norm:sp_cancel_client_order 1612 1612 0 0.00 0 1500 27 0 0
t$perf-norm:sp_cancel_customer_reserve 593 593 0 0.00 0 7319 50 0 0
t$perf-norm:sp_cancel_pay_from_customer 598 598 0 0.00 0 19958 48 0 0
t$perf-norm:sp_cancel_pay_to_supplier 354 354 0 0.00 0 1202 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 345 345 0 0.00 0 1249 35 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 73002 6678 0 0
t$perf-norm:sp_cancel_write_off 896 896 0 0.00 0 2530 21 0 0
t$perf-norm:sp_client_order 18824 18824 0 0.00 0 23385 36 0 0
t$perf-norm:sp_customer_reserve 927 927 0 0.00 0 5332 20 0 0
t$perf-norm:sp_pay_from_customer 6371 6371 0 0.00 0 17711 28 0 0
t$perf-norm:sp_pay_to_supplier 5356 5356 0 0.00 0 10260 25 0 0
t$perf-norm:sp_reserve_write_off 9170 9170 0 0.00 0 9013 26 0 0
t$perf-norm:sp_supplier_invoice 4969 4969 0 0.00 0 7578 33 0 0
t$perf-norm:sp_supplier_order 4884 4884 0 0.00 0 4367 29 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 218 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 39 3 0 0
t$perf-norm:srv_make_invnt_saldo 420 420 0 0.00 0 1148 19 0 0
t$perf-norm:srv_make_money_saldo 790 790 0 0.00 0 8904 17 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 295 74 0 0
v_add_invoice_to_stock 9260 9028 232 2.50 0 6164 26 0 232
v_all_customers 34292 34292 0 0.00 0 108 3 0 0
v_all_suppliers 27858 27858 0 0.00 0 78 4 0 0
v_all_wares 37646 37646 0 0.00 0 126 4 0 0
v_cancel_adding_invoice 702 702 0 0.00 0 9048 26 0 0
v_cancel_client_order 3224 3224 0 0.00 0 5915 13 0 0
v_cancel_customer_prepayment 1196 1196 0 0.00 0 3122 16 0 0
v_cancel_customer_reserve 1186 1186 0 0.00 1 1841 13 0 0
v_cancel_payment_to_supplier 708 708 0 0.00 0 1794 14 0 0
v_cancel_supplier_invoice 722 690 32 4.43 0 2334 26 0 32
v_cancel_supplier_order 772 772 0 0.00 0 853 10 0 0
v_cancel_write_off 1792 1792 0 0.00 0 2597 23 0 0
v_max_id_clo_ord 4881 4881 0 0.00 0 1933 5 0 0
v_max_id_clo_res 927 927 0 0.00 0 4026 19 0 0
v_max_id_ord_sup 4957 4957 0 0.00 0 11034 14 0 0
v_max_non_paid_invoice 1265 1265 0 0.00 0 333 8 0 0
v_max_non_paid_realizn 3487 3487 0 0.00 0 5695 10 0 0
v_min_id_clo_ord 4881 4881 0 0.00 0 5952 8 0 0
v_min_id_clo_res 927 927 0 0.00 0 5185 15 0 0
v_min_id_ord_sup 4957 4957 0 0.00 0 9549 15 0 0
v_min_non_paid_invoice 5356 5356 0 0.00 0 8927 24 0 0
v_min_non_paid_realizn 6371 6371 0 0.00 0 13181 23 0 0
v_our_firm 9120 9120 0 0.00 0 46 4 0 0
v_reserve_write_off 18332 18332 0 0.00 0 3170 11 0 0
x_lock_depdocs_on_canc_invoice 10736 10736 0 0.00 0 12546 122 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 6030 351 0 0
x_make_qstorno_1000_1200 4884 4884 0 0.00 2 41455 1409 0 0
x_make_qstorno_1200_2000 4969 4969 0 0.00 4 102449 3215 0 0
x_make_qstorno_2100_3300 435178 435178 0 0.00 0 43455 208 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-07-2022 10:39:32 to 20-07-2022 10:39:36.

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:21 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:22 21567 8192000000 9718871792 10016378880 799764576 8.894 100 87 172 0 828082512 7409552 20908384
08:09:47 31486 8192000000 9767398528 10076975104 837130160 9.271 100 87 174 0 869803664 12192752 20480752
08:11:32 12494 8192000000 9811902240 10133286912 874235296 9.642 100 83 164 0 920183616 27343968 18604352
08:12:58 7135 8192000000 9876753536 10215833600 927781696 10.173 100 80 158 0 994876960 49861520 17233744
08:14:09 5376 8192000000 9946687088 10293665792 968145456 10.569 100 91 175 1 1057146672 70373056 18628160
08:15:19 2022 8192000000 9989603328 10346508288 1003533712 10.913 100 94 181 0 1108482256 86885856 18062688
08:16:24 1100 8192000000 10001946592 10364600320 1033751360 11.205 100 95 176 0 1120907824 69791344 17365120
08:17:26 978 8192000000 10027645072 10402164736 1054394256 11.403 100 91 182 0 1147475280 74836752 18244272
08:18:29 934 8192000000 10017707680 10398429184 1065106112 11.505 100 95 188 1 1142220016 58132624 18981280
08:19:31 1369 8192000000 10049449776 10432401408 1071941024 11.571 100 89 175 0 1170682960 81241200 17500736
08:20:34 936 8192000000 10029220192 10406535168 1078795440 11.636 100 94 175 1 1151653920 53877040 18981440
08:21:37 1831 8192000000 10027500064 10410881024 1078796400 11.636 100 86 159 1 1149989840 54916144 16277296
08:22:40 1387 8192000000 10052776512 10439630848 1085697504 11.702 100 83 163 1 1176830576 74505712 16627360
08:23:42 848 8192000000 10049599104 10440093696 1086111184 11.706 100 90 178 2 1168091072 63810208 18169680
08:24:44 1198 8192000000 10063325712 10449129472 1091474272 11.757 100 100 186 0 1184489216 74366720 18648224
08:25:46 3091 8192000000 10046850416 10450182144 1096006784 11.800 100 71 134 1 1178408368 67069712 15331872
08:26:51 913 8192000000 10034562336 10426974208 1100779088 11.845 100 92 173 0 1160301696 40106320 19416288
08:27:53 2749 8192000000 10048108944 10438991872 1089708976 11.740 99 79 144 2 1163375680 59869952 13796752
08:28:56 1182 8192000000 10061688256 10455035904 1090769744 11.750 100 89 177 1 1188755008 80620960 17364304
08:29:58 1100 8192000000 10050642096 10434453504 1083991248 11.685 100 94 185 0 1173000976 69017152 19992576
08:31:00 1373 8192000000 10036426672 10417446912 1067838928 11.531 99 95 183 1 1155211840 67826496 19546416
08:32:02 1588 8192000000 10047230480 10432729088 1065341712 11.508 100 82 161 0 1171463104 89808992 16312400
08:33:04 1315 8192000000 9974105376 10345000960 1021278768 11.084 99 92 167 2 1099477760 59972352 18226640
08:34:07 3560 8192000000 9970750032 10352619520 1031622560 11.184 100 63 119 4 1108131408 64457136 12051712
08:35:11 7059 8192000000 9993398704 10365370368 1040970720 11.274 100 66 129 1 1119772096 66037696 12763680
08:36:19 1856 8192000000 9981232944 10367004672 1040050640 11.265 100 69 137 2 1099154096 45821136 13282320
08:37:30 2290 8192000000 9983926624 10358296576 1068649776 11.539 100 86 161 5 1102189008 17465728 16073504
08:38:42 1221 8192000000 10047745648 10426626048 1086513184 11.709 100 96 189 1 1166871664 60118176 20240304
08:39:48 12671 8192000000 10052907824 10449702912 1088530672 11.729 100 56 107 0 1179458160 80028880 10898608
08:41:16 4147 8192000000 10078653840 10465849344 1096170736 11.801 100 74 136 0 1199420688 90105216 13144736
08:42:24 7657 8192000000 10097102720 10495987712 1106273472 11.897 100 54 102 0 1212631664 94763328 11594864
08:43:39 1673 8192000000 10140589312 10531102720 1123992912 12.065 100 85 159 1 1253110144 113351424 15765808
08:44:52 4118 8192000000 10187914704 10585276416 1134266784 12.162 100 74 143 0 1307868928 158708608 14893536
08:46:00 2966 8192000000 10221086352 10613997568 1144400192 12.257 100 89 172 0 1336536544 172464288 19672064
08:47:10 3274 8192000000 10268220448 10668412928 1160460560 12.408 100 77 154 1 1380779024 202551184 17767280
08:48:16 1219 8192000000 10301430608 10710147072 1159502624 12.399 100 92 177 0 1408292560 228997024 19792912
08:49:22 1193 8192000000 10295907392 10719264768 1181011040 12.600 100 88 175 1 1401126960 199668992 20446928
08:50:26 1112 8192000000 10376139360 10780573696 1193932784 12.720 100 90 180 0 1483302160 269089840 20279536
08:51:31 1417 8192000000 10379427744 10795892736 1198855120 12.766 100 81 155 0 1489970368 271827072 19288176
08:52:39 1017 8192000000 10394365792 10803392512 1182692912 12.615 100 94 181 0 1501536960 298498288 20345760
08:53:43 1340 8192000000 10432240288 10856173568 1184009088 12.628 100 99 197 1 1546301888 339028832 23263968
08:54:47 1386 8192000000 10459704576 10891296768 1192478912 12.706 100 99 196 1 1568406160 352865312 23061936
08:55:51 1022 8192000000 10493403616 10911760384 1204542448 12.818 100 96 191 0 1596761168 370196896 22021824
08:56:55 855 8192000000 10509829920 10951979008 1204527056 12.818 100 97 187 0 1617434496 389676016 23231424
08:57:59 1534 8192000000 10479868016 10924445696 1201820224 12.793 100 80 158 0 1588046400 366852576 19373600
08:59:05 4082 8192000000 10490776688 10938122240 1198347328 12.761 100 62 122 0 1607346112 394004960 14993824
09:00:15 6168 8192000000 10481961424 10916839424 1188386192 12.668 100 56 110 0 1588649616 386642160 13621264
09:01:26 16745 8192000000 10450084992 10911367168 1148170224 12.292 98 48 94 0 1547876784 387214624 12491936
09:03:07 21085 8192000000 10440047968 10932207616 1134416624 12.163 99 35 70 0 1564445920 420100336 9928960
09:04:34 40633 8192000000 10203583920 10707988480 1130261536 12.124 99 28 55 0 1334698560 197756944 6680080
09:06:27 53798 8192000000 10141705280 10604359680 1114917280 11.979 98 19 38 1 1289383360 169084032 5382048
09:08:28 48641 8192000000 10084774720 10532593664 1113116768 11.962 98 37 71 1 1178076064 57389920 7569376
09:10:18 35484 8192000000 10074840800 10551689216 1101516928 11.852 98 21 41 0 1178848064 73267312 4063824
09:11:54 13976 8192000000 10090250240 10520862720 1117636384 12.005 99 62 112 4 1187927696 58581632 11709680
09:13:09 97972 8192000000 10056875600 10526670848 1097089696 11.810 97 21 38 0 1152707760 50875232 4742832
09:15:59 131044 8192000000 10077079248 10513633280 1089087888 11.734 96 42 72 2 1155665056 58774464 7802704
09:19:11 74203 8192000000 10049732288 10513727488 1118082176 12.009 99 16 32 0 1165881552 44521088 3278288
09:21:32 20676 8192000000 10061090784 10506657792 1131982144 12.140 100 23 44 0 1164503424 27916160 4605120
09:22:53 39799 8192000000 10087752336 10531467264 1115400864 11.984 98 12 24 0 1177989104 59442224 3146016
09:24:39 32139 8192000000 10111044896 10561884160 1137068576 12.188 100 40 80 0 1214561008 69138624 8353808
09:26:11 100277 8192000000 10068195184 10555600896 1113317856 11.964 98 25 46 1 1189841696 71217520 5306320
09:29:22 118191 8192000000 10102175488 10607706112 1102651168 11.863 96 15 30 0 1169646928 63945616 3050144
09:32:20 85592 8192000000 10065415056 10529804288 1088753360 11.731 96 19 38 0 1124380992 31962768 3664864
09:34:47 17347 8192000000 10063390496 10509672448 1124194752 12.067 99 48 94 0 1153874672 19142144 10537776
09:36:06 39570 8192000000 10096371808 10564771840 1138738368 12.204 100 26 52 0 1188262016 44130208 5393440
09:37:46 34479 8192000000 10117737648 10546774016 1113758096 11.968 98 52 104 0 1187904576 62969744 11176736
09:39:21 74986 8192000000 10111893440 10557665280 1125657520 12.080 99 16 32 0 1180360432 51483392 3219520
09:42:01 42861 8192000000 10088512048 10629509120 1126657504 12.090 100 12 24 0 1232316528 103116736 2542288
09:43:49 123592 8192000000 10069598752 10534084608 1124931328 12.074 99 24 46 0 1172343872 42878224 4534320
09:47:01 1071 8192000000 10080552672 10499477504 1133426928 12.154 100 80 154 0 1181516896 31773600 16316368
09:48:04 68606 8192000000 10073933936 10560671744 1143553776 12.249 100 16 32 0 1210225088 63609424 3061888
09:50:18 76258 8192000000 10090172160 10550808576 1128213008 12.105 99 16 32 0 1173147008 41316272 3617728
09:52:37 87372 8192000000 10088153120 10540953600 1137160160 12.189 100 17 34 0 1215241424 74278144 3803120
09:55:05 102939 8192000000 10096446560 10561998848 1112277664 11.954 97 9 18 0 1159880752 45319088 2284000
09:57:48 45271 8192000000 10118474416 10570948608 1123892944 12.064 98 24 47 1 1204775696 75823648 5059104
09:59:33 60631 8192000000 10089009776 10538127360 1133989408 12.159 100 29 58 0 1185112928 45015376 6108144
10:01:38 47586 8192000000 10180729680 10629787648 1123481712 12.060 98 44 87 1 1251997776 118915248 9600816
10:03:26 31768 8192000000 10104396560 10564960256 1098219488 11.821 97 29 58 1 1180590256 76316192 6054576
10:05:01 66723 8192000000 10076497728 10543136768 1053957488 11.399 95 26 52 0 1114852064 55954800 4939776
10:07:12 119595 8192000000 10039594272 10487943168 1087614880 11.720 98 19 38 0 1155325312 63433520 4276912
10:10:31 17464 8192000000 10051576512 10471260160 1097595984 11.815 99 46 88 0 1146419264 40330608 8492672
10:11:50 81852 8192000000 10052296288 10513809408 1099282624 11.831 98 10 19 1 1130586672 29756608 1547440
10:14:13 58539 8192000000 10113553344 10573561856 1118935088 12.017 99 15 30 0 1220440256 97889648 3615520
10:16:12 71770 8192000000 10195578000 10635456512 1137117344 12.188 100 15 30 1 1277887328 137433968 3336016
10:18:29 66958 8192000000 10149561008 10620940288 1117906800 12.007 99 27 54 0 1270948800 146375520 6666480
10:20:44 54774 8192000000 10131924672 10568220672 1118444800 12.012 99 36 70 1 1216423344 90099328 7879216
10:22:39 53074 8192000000 10163008880 10616623104 1114669216 11.977 98 37 74 0 1232406256 109841632 7895408
10:24:47 43612 8192000000 10196227328 10651389952 1111430928 11.946 98 15 30 0 1254197856 139550288 3216640
10:26:34 74382 8192000000 10084794608 10563719168 1101387648 11.851 98 22 44 0 1192591312 86809536 4394128
10:28:54 81457 8192000000 10137028176 10587410432 1122352624 12.049 100 45 89 0 1234277600 102886160 9038816
10:31:18 58940 8192000000 10043068000 10520600576 1081810640 11.665 96 31 60 0 1168381472 80246752 6324080
10:33:19 53726 8192000000 10044323744 10498363392 1084957664 11.695 97 12 24 0 1117347440 29846128 2543648
10:35:24 54248 8192000000 10052029584 10537852928 1111355808 11.945 100 10 20 0 1165056944 51646496 2054640

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-07-2022 10:39:38 to 20-07-2022 10:39:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-07-2022 10:39:55 to 20-07-2022 10:39:57.

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          205313
MON$OLDEST_ACTIVE               207098
MON$OLDEST_SNAPSHOT             207098
MON$NEXT_TRANSACTION            207098
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                       2046048
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             752
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2799 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2799 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2799 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2799 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 ---
:195: 
:196: mscs-test-161.budzdorov.ru    Wed Jul 20 10:37:31 2022
:197:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:198: 
--- end of diff output ---

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

20.07.22 10:39:58. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-07-20 10:40:00 No severe PSQL-related problems occured
20.07.22 10:40:01. End of report.