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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16454512 kB
MemAvailable 31597504 kB
Buffers 0 kB
Cached 15284360 kB
SwapCached 2876 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-26 07:37:15
DTS_END                         2022-04-26 07:37:15.5790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47472
TRN_ID                          224371
ATT_ID                          663
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: 8937.17 28327 1072460
customer order: creation 170.78 445 20493
customer order: refuse 14.43 401 1732
order to supplier: creation 46.47 1275 5576
order to supplier: removal 3.33 177490 400
invoice (draft): creation 47.05 3013 5646
invoice (draft): removal 49.08 1023 5890
invoice accept: apply 41.49 26548 4979
invoice accept: cancel 43.98 1161 5278
customer reserve: creation 3877.36 252 465283
customer reserve: removal 4346.47 107 521576
realization accept: apply 84.32 259 10118
realization accept: cancel 80.50 178 9660
payment from customer: creation 60.44 162 7253
payment from customer: removal 5.83 114 700
payment to supplier: creation 48.84 194 5861
payment to supplier: removal 3.58 111 429
service: total inventory turnovers 4.42 3748 530
service: total monetary turnovers 7.73 324 928
service: aggregate perf. data 1.03 15090 124
service: refresh index statistics 0.03 334638 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-04-2022 07:39:15 to 26-04-2022 07:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8839
TEST_TIME 2 7028
TEST_TIME 3 6583
TEST_TIME 4 6190
TEST_TIME 5 6218
TEST_TIME 6 6557
TEST_TIME 7 6588
TEST_TIME 8 6602
TEST_TIME 9 6583
TEST_TIME 10 7025
TEST_TIME 11 7126
TEST_TIME 12 7259
TEST_TIME 13 7199
TEST_TIME 14 7156
TEST_TIME 15 7158
TEST_TIME 16 7207
TEST_TIME 17 7525
TEST_TIME 18 7658
TEST_TIME 19 7606
TEST_TIME 20 7697
TEST_TIME 21 7927
TEST_TIME 22 8079
TEST_TIME 23 8416
TEST_TIME 24 8457
TEST_TIME 25 8711
TEST_TIME 26 8942
TEST_TIME 27 9454
TEST_TIME 28 9417
TEST_TIME 29 10130
TEST_TIME 30 10443
TEST_TIME 31 10830
TEST_TIME 32 11345
TEST_TIME 33 11171
TEST_TIME 34 11286
TEST_TIME 35 11503
TEST_TIME 36 11257
TEST_TIME 37 10957
TEST_TIME 38 10841
TEST_TIME 39 10796
TEST_TIME 40 10689
TEST_TIME 41 10674
TEST_TIME 42 10477
TEST_TIME 43 10408
TEST_TIME 44 10332
TEST_TIME 45 10328
TEST_TIME 46 10289
TEST_TIME 47 10225
TEST_TIME 48 10265
TEST_TIME 49 10241
TEST_TIME 50 10174
TEST_TIME 51 10140
TEST_TIME 52 10062
TEST_TIME 53 9985
TEST_TIME 54 9916
TEST_TIME 55 9826
TEST_TIME 56 9926
TEST_TIME 57 9887
TEST_TIME 58 9811
TEST_TIME 59 9882
TEST_TIME 60 9843
TEST_TIME 61 9799
TEST_TIME 62 9851
TEST_TIME 63 9778
TEST_TIME 64 9812
TEST_TIME 65 9854
TEST_TIME 66 9799
TEST_TIME 67 9824
TEST_TIME 68 9771
TEST_TIME 69 9688
TEST_TIME 70 9587
TEST_TIME 71 9578
TEST_TIME 72 9498
TEST_TIME 73 9418
TEST_TIME 74 9391
TEST_TIME 75 9384
TEST_TIME 76 9389
TEST_TIME 77 9344
TEST_TIME 78 9299
TEST_TIME 79 9253
TEST_TIME 80 9223
TEST_TIME 81 9221
TEST_TIME 82 9280
TEST_TIME 83 9340
TEST_TIME 84 9314
TEST_TIME 85 9416
TEST_TIME 86 9585
TEST_TIME 87 9537
TEST_TIME 88 9474
TEST_TIME 89 9464
TEST_TIME 90 9403
TEST_TIME 91 9331
TEST_TIME 92 9245
TEST_TIME 93 9269
TEST_TIME 94 9204
TEST_TIME 95 9212
TEST_TIME 96 9155
TEST_TIME 97 9150
TEST_TIME 98 9137
TEST_TIME 99 9169
TEST_TIME 100 9150
TEST_TIME 101 9172
TEST_TIME 102 9158
TEST_TIME 103 9133
TEST_TIME 104 9143
TEST_TIME 105 9200
TEST_TIME 106 9173
TEST_TIME 107 9152
TEST_TIME 108 9171
TEST_TIME 109 9162
TEST_TIME 110 9189
TEST_TIME 111 9168
TEST_TIME 112 9200
TEST_TIME 113 9126
TEST_TIME 114 9112
TEST_TIME 115 9083
TEST_TIME 116 9071
TEST_TIME 117 9016
TEST_TIME 118 9033
TEST_TIME 119 9026
TEST_TIME 120 8962

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-04-2022 07:39:18 to 26-04-2022 07:39:22.

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 1500237 1500237 0 0.00 0 22030 14 0 0
doc_list_biud 1500211 1500211 0 0.00 0 24696 43 0 0
sp_add_invoice_to_stock 5087 4979 108 2.12 66 197222 26548 0 108
sp_cancel_adding_invoice 5278 5278 0 0.00 6 57072 1162 0 0
sp_cancel_client_order 1732 1732 0 0.00 7 12834 401 0 0
sp_cancel_customer_reserve 521576 521576 0 0.00 1 24699 108 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 3 3761 114 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 4 3307 111 0 0
sp_cancel_supplier_invoice 5906 5890 16 0.27 3 24199 1024 0 16
sp_cancel_supplier_order 400 400 0 0.00 9 1348716 177491 0 0
sp_cancel_write_off 9660 9660 0 0.00 2 17901 178 0 0
sp_client_order 20493 20493 0 0.00 6 31097 446 0 0
sp_customer_reserve 465283 465283 0 0.00 1 54352 252 0 0
sp_fill_shopping_cart 31703 31703 0 0.00 1 28748 80 0 0
sp_fill_shopping_cart_clo_res 465236 465236 0 0.00 0 12753 17 0 0
sp_get_clo_for_invoice 4931 4931 0 0.00 0 29267 335 0 0
sp_kill_cost_storno 16067 16067 0 0.00 0 5809 71 0 0
sp_kill_qstorno_ret_qs2qd 1059102 1059102 0 0.00 0 24694 47 0 0
sp_kill_qty_storno 559582 559582 0 0.00 0 24695 106 0 0
sp_lock_selected_doc 20852 20852 0 0.00 0 9994 7 0 0
sp_make_cost_storno 28163 28163 0 0.00 0 21447 79 0 0
sp_multiply_rows_for_pdistr 28161 28161 0 0.00 0 13193 37 0 0
sp_multiply_rows_for_qdistr 20493 20493 0 0.00 0 20369 171 0 0
sp_pay_from_customer 7253 7253 0 0.00 4 15933 163 0 0
sp_pay_to_supplier 5861 5861 0 0.00 6 14614 195 0 0
sp_payment_common 13112 13112 0 0.00 2 7834 45 0 0
sp_qd_handle_on_cancel_clo 1732 1732 0 0.00 0 12815 333 0 0
sp_qd_handle_on_invoice_upd_sts 10208 10208 0 0.00 0 21551 509 0 0
sp_qd_handle_on_reserve_upd_sts 10116 10116 0 0.00 0 17879 63 0 0
sp_reserve_write_off 10118 10118 0 0.00 8 22061 260 0 0
sp_supplier_invoice 5646 5646 0 0.00 10 89897 3013 0 0
sp_supplier_order 5576 5576 0 0.00 8 25118 1276 0 0
srv_aggregate_perf_data 124 124 0 0.00 2533 145554 15090 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 708 134555 35466 0 0
srv_find_qd_qs_mism 1022802 1022802 0 0.00 0 14429 11 0 0
srv_make_invnt_saldo 530 530 0 0.00 61 153400 3748 0 0
srv_make_money_saldo 928 928 0 0.00 1 7741 325 0 0
srv_recalc_idx_stat 4 4 0 0.00 209174 447315 334638 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 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 4984 4984 0 0.00 0 12780 849 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 4923 394 0 0
t$perf-norm:sp_cancel_client_order 1732 1732 0 0.00 0 3535 28 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 670 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 1341 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 417 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 1410 32 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 91989 6923 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 1405 16 0 0
t$perf-norm:sp_client_order 20493 20493 0 0.00 0 12301 35 0 0
t$perf-norm:sp_customer_reserve 999 999 0 0.00 0 1907 16 0 0
t$perf-norm:sp_pay_from_customer 7253 7253 0 0.00 0 6401 18 0 0
t$perf-norm:sp_pay_to_supplier 5861 5861 0 0.00 0 6338 20 0 0
t$perf-norm:sp_reserve_write_off 10119 10119 0 0.00 0 6610 18 0 0
t$perf-norm:sp_supplier_invoice 5646 5646 0 0.00 0 8408 30 0 0
t$perf-norm:sp_supplier_order 5576 5576 0 0.00 0 9428 32 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 327 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 304 11 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 2460 19 0 0
t$perf-norm:srv_make_money_saldo 928 928 0 0.00 0 2449 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 201 72 0 0
v_add_invoice_to_stock 10068 9852 216 2.14 0 6432 27 0 216
v_all_customers 37714 37714 0 0.00 0 59 3 0 0
v_all_suppliers 31370 31370 0 0.00 0 70 4 0 0
v_all_wares 40980 40980 0 0.00 0 55 3 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 403 13 0 0
v_cancel_client_order 3464 3464 0 0.00 0 3169 9 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 1314 10 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 4229 12 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 834 11 0 0
v_cancel_supplier_invoice 876 844 32 3.65 0 6338 35 0 32
v_cancel_supplier_order 800 800 0 0.00 0 377 10 0 0
v_cancel_write_off 2062 2062 0 0.00 0 4542 23 0 0
v_max_id_clo_ord 5570 5570 0 0.00 0 4708 5 0 0
v_max_id_clo_res 999 999 0 0.00 0 3765 14 0 0
v_max_id_ord_sup 5640 5640 0 0.00 0 5077 7 0 0
v_max_non_paid_invoice 1383 1383 0 0.00 0 58 7 0 0
v_max_non_paid_realizn 3720 3720 0 0.00 0 1238 7 0 0
v_min_id_clo_ord 5570 5570 0 0.00 0 4689 8 0 0
v_min_id_clo_res 999 999 0 0.00 0 942 7 0 0
v_min_id_ord_sup 5640 5640 0 0.00 0 4965 8 0 0
v_min_non_paid_invoice 5859 5859 0 0.00 0 5477 16 0 0
v_min_non_paid_realizn 7253 7253 0 0.00 0 7827 20 0 0
v_our_firm 10330 10330 0 0.00 0 51 4 0 0
v_reserve_write_off 20232 20232 0 0.00 0 5830 10 0 0
x_lock_depdocs_on_canc_invoice 10746 10746 0 0.00 0 15010 120 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 7216 335 0 0
x_make_qstorno_1000_1200 5576 5576 0 0.00 3 25090 1109 0 0
x_make_qstorno_1200_2000 5646 5646 0 0.00 4 85710 2801 0 0
x_make_qstorno_2100_3300 465283 465283 0 0.00 0 54306 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-04-2022 07:39:23 to 26-04-2022 07:39:27.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:19 3 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:20 47336 8192000000 9740243936 10042777600 806546208 8.963 100 90 180 0 836418912 8010416 21862288
05:10:10 19144 8192000000 9760007520 10069340160 834260624 9.242 100 91 182 0 870821088 14684192 21876272
05:11:54 11109 8192000000 9832681056 10162434048 893514832 9.834 100 78 156 1 946745616 35766912 17463872
05:13:09 6777 8192000000 9904607296 10244952064 940995184 10.303 100 85 168 2 1022778304 63730720 18052400
05:14:19 2782 8192000000 9941598432 10290077696 978523792 10.670 100 88 173 0 1059705440 62942944 18238704
05:15:25 2508 8192000000 9994288000 10355916800 1009725696 10.973 100 85 165 0 1111259856 84121808 17412352
05:16:29 875 8192000000 10014700224 10380066816 1035117712 11.218 100 91 179 0 1127069744 74957120 16994912
05:17:32 1190 8192000000 10029974224 10403872768 1050204256 11.363 100 96 180 0 1147732128 79913648 17614224
05:18:35 1727 8192000000 10042692736 10426441728 1060510272 11.461 100 98 191 1 1167662640 86651696 20500672
05:19:38 1164 8192000000 10051600432 10433421312 1069814896 11.550 100 94 181 2 1175047664 86669472 18563296
05:20:42 1670 8192000000 10052157952 10435858432 1074891088 11.599 100 94 179 0 1171427760 78614256 17922416
05:21:47 1076 8192000000 10053583744 10441330688 1082678256 11.673 100 96 189 0 1172534784 69691904 20164624
05:22:50 1348 8192000000 10065895472 10454822912 1084782736 11.693 100 93 177 0 1189166512 86752832 17630944
05:23:52 1132 8192000000 10049336512 10436141056 1093246448 11.774 100 91 174 0 1173411280 62495744 17669088
05:24:55 1337 8192000000 10071473088 10460733440 1097666256 11.815 100 95 184 0 1192777488 76165216 18946016
05:25:58 1489 8192000000 10096413392 10491330560 1104805072 11.883 100 93 183 3 1221681648 97813920 19062656
05:27:00 1300 8192000000 10084440192 10481582080 1102495168 11.861 100 88 165 1 1207381664 88217536 16668960
05:28:03 1061 8192000000 10040289472 10428588032 1091943872 11.761 99 94 175 5 1161035104 51515552 17575680
05:29:05 1115 8192000000 10057017360 10445320192 1092093856 11.763 100 91 173 0 1175787696 65115328 18578512
05:30:08 1321 8192000000 10063212816 10454405120 1096234720 11.802 100 96 186 1 1191686912 77858704 17593488
05:31:10 1431 8192000000 10029193072 10407243776 1056638112 11.424 100 95 181 2 1149847120 74444640 18764368
05:32:13 1126 8192000000 10049291792 10429030400 1074543104 11.595 100 97 193 1 1164030256 69626064 19861088
05:33:16 1486 8192000000 10021572912 10398601216 1063647280 11.491 100 96 187 0 1144325104 61229536 19448288
05:34:18 1604 8192000000 10001630112 10386071552 1047086848 11.333 100 79 153 2 1126657328 63628528 15941952
05:35:22 793 8192000000 9991379216 10368929792 1042008432 11.284 100 83 161 0 1112370816 53589552 16772832
05:36:23 3407 8192000000 9972544608 10344460288 1023282112 11.104 98 73 136 1 1088373664 50340848 14750704
05:37:30 2751 8192000000 9985342960 10365923328 1046735856 11.329 100 66 122 1 1109663776 51307200 11620720
05:38:38 1070 8192000000 10028937472 10405208064 1060234800 11.459 100 91 172 0 1149642208 71307200 18100208
05:39:43 7077 8192000000 10032212304 10415915008 1068477584 11.538 100 76 137 1 1157595840 75706464 13411792
05:40:57 7409 8192000000 10049321712 10439352320 1087578640 11.720 100 64 126 0 1166759264 66829520 12351104
05:42:10 7289 8192000000 10111167728 10499076096 1103367312 11.870 100 66 126 2 1220848640 103838560 13642768
05:43:21 1138 8192000000 10134244080 10538328064 1117245792 12.001 100 88 173 0 1256914864 120151984 19517088
05:44:26 11538 8192000000 10177637600 10580074496 1130941232 12.130 100 70 138 0 1297165088 151415312 14808544
05:45:50 965 8192000000 10206871536 10603360256 1156704848 12.372 100 90 176 0 1321348704 144458496 20185360
05:46:54 2435 8192000000 10286976784 10681999360 1167569424 12.474 100 90 180 1 1397779408 210616384 19593600
05:47:59 1433 8192000000 10299517120 10700582912 1182664688 12.615 100 91 177 2 1404257648 202112288 19480672
05:49:02 1454 8192000000 10335907888 10738126848 1188265024 12.667 100 94 184 1 1440725712 233013760 19446928
05:50:07 992 8192000000 10354945200 10756575232 1202445440 12.799 100 94 178 0 1469568016 246823968 20298608
05:51:11 2742 8192000000 10390794816 10798358528 1201161712 12.787 100 85 165 1 1497216800 276151168 19903920
05:52:19 1038 8192000000 10452770448 10855882752 1207962544 12.850 100 98 190 1 1561836400 331427968 22445888
05:53:23 961 8192000000 10462926160 10884165632 1195829584 12.738 99 96 187 2 1558481136 340844512 21807040
05:54:27 1197 8192000000 10490095392 10906951680 1190120752 12.684 99 99 196 1 1597240176 384287888 22831536
05:55:31 835 8192000000 10511324736 10922549248 1200418432 12.780 100 86 167 1 1621296544 401017584 19860528
05:56:36 1647 8192000000 10547462512 10958823424 1209494288 12.864 100 98 191 0 1653045936 420744656 22806992
05:57:40 2504 8192000000 10542149664 11008077824 1199731104 12.774 100 75 147 1 1652195568 434514352 17950112
05:58:49 701 8192000000 10533284384 11009826816 1191568400 12.698 100 95 187 3 1639324720 425164096 22592224
05:59:54 1034 8192000000 10550578912 11001442304 1199986592 12.776 100 90 177 1 1652264800 430388880 21889328
06:00:57 16207 8192000000 10533628224 10980114432 1179458288 12.585 99 58 116 0 1645983664 451894256 14631120
06:02:17 4823 8192000000 10572384336 11036749824 1190010112 12.683 100 68 135 3 1675148704 467080688 18057904
06:03:24 18802 8192000000 10565822912 11021901824 1184360496 12.631 100 45 89 0 1653415744 457337712 11717536
06:04:51 11286 8192000000 10483702528 10940698624 1177507152 12.567 100 34 66 0 1595050064 408140000 9402912
06:06:07 15662 8192000000 10401686448 10941173760 1157921248 12.384 100 27 54 1 1548980576 383966080 7093248
06:07:26 47127 8192000000 10260237248 10764455936 1107588368 11.910 96 16 32 1 1342051424 230272128 4190928
06:09:14 87199 8192000000 10105471664 10540748800 1101041344 11.848 97 21 42 0 1178116528 72400272 4674912
06:11:53 84525 8192000000 10049830496 10576248832 1097458112 11.814 97 12 22 3 1208023872 107709968 2855792
06:14:38 60805 8192000000 10060448448 10495946752 1110628560 11.938 100 35 55 1 1128976624 13955872 4392192
06:16:43 113368 8192000000 10077632736 10528575488 1085903472 11.704 95 24 44 0 1147849136 57823216 4122448
06:19:38 38239 8192000000 10076589312 10509574144 1111950880 11.951 98 34 64 3 1170218656 49681088 8586688
06:21:17 12726 8192000000 10108074912 10549428224 1146777024 12.279 100 34 67 1 1212608624 58737136 7094464
06:22:34 9465 8192000000 10151986848 10581680128 1160557472 12.408 100 74 142 3 1244604640 67468432 16578736
06:23:52 40974 8192000000 10179122944 10659065856 1160752320 12.410 100 25 50 1 1302977360 136571536 5653504
06:26:40 27232 8192000000 10093621504 10540359680 1121002816 12.036 99 25 47 0 1184492560 57731152 5758592
06:28:09 47611 8192000000 10064230768 10540908544 1120229552 12.029 99 11 22 1 1165034272 42135456 2669264
06:30:18 95894 8192000000 10054516640 10525421568 1086341904 11.708 96 19 37 0 1131111072 40894128 3875040
06:32:55 82797 8192000000 10062164480 10552172544 1103945392 11.875 98 16 32 0 1160256992 52533968 3777632
06:35:23 23411 8192000000 10090172832 10521767936 1120072688 12.028 100 37 74 1 1176875920 49456768 7346464
06:36:50 46746 8192000000 10069855216 10523811840 1108350784 11.917 98 13 26 1 1151182256 40365616 2465856
06:38:39 86213 8192000000 10084300000 10531528704 1125090528 12.075 99 23 47 3 1178917808 48932432 4894848
06:41:15 56656 8192000000 10072310896 10526031872 1091748304 11.759 97 22 43 0 1152288016 56092240 4447472
06:43:15 24749 8192000000 10064682800 10501513216 1100228896 11.840 98 37 74 2 1143819920 36202224 7388800
06:44:41 134555 8192000000 10045433376 10470236160 1062487392 11.480 94 26 52 0 1084731856 17804960 4439504
06:47:57 31405 8192000000 10089953376 10550587392 1111533296 11.947 99 38 74 1 1168890608 50128192 7229120
06:49:29 49679 8192000000 10071520336 10542088192 1113640144 11.967 99 8 15 0 1162745648 47875408 1230096
06:51:23 68141 8192000000 10084245520 10523271168 1060347056 11.460 94 15 30 0 1119681296 56124832 3209408
06:53:37 36428 8192000000 10110539808 10551103488 1119063200 12.018 98 25 50 0 1207281360 82385744 5832416
06:55:14 30191 8192000000 10142853008 10568323072 1097842496 11.817 96 33 66 0 1209671984 103890528 7938960
06:56:47 9790 8192000000 10200572320 10626936832 1140001536 12.216 100 57 114 0 1289769536 136860624 12907376
06:58:03 8805 8192000000 10250851648 10676129792 1137175040 12.189 99 61 118 1 1334606896 184166384 13265472
06:59:13 31780 8192000000 10245485696 10696396800 1141832128 12.233 100 36 72 0 1367753920 216376176 9545616
07:01:05 28463 8192000000 10223762656 10662092800 1143675008 12.250 100 29 58 2 1311168624 161237648 6255968
07:02:35 41829 8192000000 10173560080 10632646656 1124597296 12.070 99 21 42 1 1283481616 154245264 4639056
07:04:22 74351 8192000000 10107568512 10551107584 1120715888 12.034 98 39 77 1 1186292064 57402272 8173904
07:06:39 71094 8192000000 10097605168 10591617024 1144329840 12.256 100 6 12 0 1215850864 70444320 1076704
07:08:50 114609 8192000000 10103845872 10613506048 1131665712 12.137 99 15 30 1 1210242096 75016560 3559824
07:12:00 58821 8192000000 10102639296 10555314176 1141405872 12.229 100 20 39 1 1200246048 54407168 4433008
07:14:10 48756 8192000000 10111926720 10604904448 1137672976 12.194 100 8 16 0 1210385008 70879808 1832224
07:16:45 63653 8192000000 10093845088 10541563904 1138056160 12.197 99 32 64 3 1187052400 42831824 6164416
07:18:49 33249 8192000000 10076074704 10513281024 1118192640 12.010 97 38 76 0 1144954016 19620544 7140832
07:20:22 19642 8192000000 10105054448 10532954112 1115584592 11.985 98 41 82 1 1191494608 67657408 8252608
07:22:06 72473 8192000000 10072780080 10505555968 1094021024 11.781 96 31 62 1 1131602592 31657024 5924544
07:24:25 50184 8192000000 10116393568 10551971840 1086140400 11.706 96 58 114 2 1174345824 76949728 11255696
07:26:16 47329 8192000000 10081411600 10554310656 1127934080 12.102 99 20 40 3 1188063936 56105936 4023920
07:28:14 33489 8192000000 10112663296 10556932096 1096165600 11.801 96 44 88 0 1140759008 35437984 9155424
07:31:00 78084 8192000000 10133319008 10581159936 1123432032 12.059 98 31 60 4 1207347760 77495152 6420576
07:33:32 44750 8192000000 10119397776 10597380096 1125976576 12.083 99 14 28 0 1217047440 88007968 3062896
07:35:17 90024 8192000000 10073236688 10551558144 1115187376 11.982 98 12 24 1 1155800592 37850704 2762512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 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 26-04-2022 07:39:46 to 26-04-2022 07:39:48.

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          222288
MON$OLDEST_ACTIVE               224486
MON$OLDEST_SNAPSHOT             224486
MON$NEXT_TRANSACTION            224486
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                       2058976
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:1372: 
:1373: mscs-test-161.budzdorov.ru    Tue Apr 26 07:37:17 2022
:1374:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1375: 
:1376: 
:1377: mscs-test-161.budzdorov.ru    Tue Apr 26 07:37:39 2022
:1378:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1379: 
:1380: 
:1381: mscs-test-161.budzdorov.ru    Tue Apr 26 07:37:39 2022
:1382:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1383: 
:1384: 
:1385: mscs-test-161.budzdorov.ru    Tue Apr 26 07:37:39 2022
:1386:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1387: 
:1388: 
:1389: mscs-test-161.budzdorov.ru    Tue Apr 26 07:37:39 2022
:1390:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1391: 
:1392: 
:1393: mscs-test-161.budzdorov.ru    Tue Apr 26 07:37:39 2022
:1394:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1395: 
:1396: 
:1397: mscs-test-161.budzdorov.ru    Tue Apr 26 07:37:39 2022
:1398:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1399: 
--- end of diff output ---

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

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