Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.04.2022 04:37
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 16047060 kB
MemAvailable 31475796 kB
Buffers 20 kB
Cached 15557848 kB
SwapCached 2280 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-08 04:37:15
DTS_END                         2022-04-08 04:37:15.0990
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/53380
TRN_ID                          213301
ATT_ID                          641
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: 9002.11 27400 1080253
customer order: creation 165.09 465 19811
customer order: refuse 14.00 443 1680
order to supplier: creation 43.09 1436 5171
order to supplier: removal 3.52 186527 422
invoice (draft): creation 43.59 3028 5231
invoice (draft): removal 51.43 1044 6172
invoice accept: apply 40.36 25275 4843
invoice accept: cancel 46.93 1222 5631
customer reserve: creation 3727.23 239 447267
customer reserve: removal 4587.11 113 550453
realization accept: apply 78.99 293 9479
realization accept: cancel 75.96 165 9115
payment from customer: creation 57.08 193 6850
payment from customer: removal 5.54 113 665
payment to supplier: creation 47.46 207 5695
payment to supplier: removal 3.44 163 413
service: total inventory turnovers 3.34 5146 401
service: total monetary turnovers 7.10 406 852
service: aggregate perf. data 0.82 18650 98
service: refresh index statistics 0.03 302880 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-04-2022 04:38:05 to 08-04-2022 04:38:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7086
TEST_TIME 2 6420
TEST_TIME 3 5589
TEST_TIME 4 6470
TEST_TIME 5 6294
TEST_TIME 6 6252
TEST_TIME 7 6140
TEST_TIME 8 6127
TEST_TIME 9 6148
TEST_TIME 10 6071
TEST_TIME 11 5990
TEST_TIME 12 6035
TEST_TIME 13 6102
TEST_TIME 14 6092
TEST_TIME 15 6192
TEST_TIME 16 6371
TEST_TIME 17 6471
TEST_TIME 18 6667
TEST_TIME 19 6937
TEST_TIME 20 7082
TEST_TIME 21 7545
TEST_TIME 22 7669
TEST_TIME 23 7912
TEST_TIME 24 8156
TEST_TIME 25 8200
TEST_TIME 26 8045
TEST_TIME 27 8264
TEST_TIME 28 8736
TEST_TIME 29 8636
TEST_TIME 30 9065
TEST_TIME 31 9504
TEST_TIME 32 9835
TEST_TIME 33 10021
TEST_TIME 34 9972
TEST_TIME 35 10250
TEST_TIME 36 10337
TEST_TIME 37 10309
TEST_TIME 38 10573
TEST_TIME 39 10636
TEST_TIME 40 10546
TEST_TIME 41 10425
TEST_TIME 42 10288
TEST_TIME 43 10211
TEST_TIME 44 10143
TEST_TIME 45 10113
TEST_TIME 46 10076
TEST_TIME 47 9998
TEST_TIME 48 9942
TEST_TIME 49 9920
TEST_TIME 50 9878
TEST_TIME 51 10040
TEST_TIME 52 9949
TEST_TIME 53 9882
TEST_TIME 54 9753
TEST_TIME 55 9672
TEST_TIME 56 9691
TEST_TIME 57 9571
TEST_TIME 58 9487
TEST_TIME 59 9481
TEST_TIME 60 9429
TEST_TIME 61 9387
TEST_TIME 62 9364
TEST_TIME 63 9499
TEST_TIME 64 9445
TEST_TIME 65 9418
TEST_TIME 66 9357
TEST_TIME 67 9287
TEST_TIME 68 9352
TEST_TIME 69 9323
TEST_TIME 70 9237
TEST_TIME 71 9244
TEST_TIME 72 9307
TEST_TIME 73 9261
TEST_TIME 74 9327
TEST_TIME 75 9289
TEST_TIME 76 9265
TEST_TIME 77 9389
TEST_TIME 78 9402
TEST_TIME 79 9450
TEST_TIME 80 9402
TEST_TIME 81 9379
TEST_TIME 82 9294
TEST_TIME 83 9273
TEST_TIME 84 9218
TEST_TIME 85 9152
TEST_TIME 86 9155
TEST_TIME 87 9120
TEST_TIME 88 9097
TEST_TIME 89 9068
TEST_TIME 90 9101
TEST_TIME 91 9022
TEST_TIME 92 9003
TEST_TIME 93 8972
TEST_TIME 94 8951
TEST_TIME 95 8918
TEST_TIME 96 8897
TEST_TIME 97 8917
TEST_TIME 98 8901
TEST_TIME 99 8900
TEST_TIME 100 8901
TEST_TIME 101 8859
TEST_TIME 102 8878
TEST_TIME 103 8894
TEST_TIME 104 8934
TEST_TIME 105 8971
TEST_TIME 106 9021
TEST_TIME 107 9061
TEST_TIME 108 9200
TEST_TIME 109 9167
TEST_TIME 110 9208
TEST_TIME 111 9172
TEST_TIME 112 9136
TEST_TIME 113 9108
TEST_TIME 114 9076
TEST_TIME 115 9068
TEST_TIME 116 9078
TEST_TIME 117 9042
TEST_TIME 118 9066
TEST_TIME 119 9077
TEST_TIME 120 9028

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-04-2022 04:38:09 to 08-04-2022 04:38:13.

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 1494049 1494049 0 0.00 0 35169 13 0 0
doc_list_biud 1494038 1494038 0 0.00 0 49181 48 0 0
sp_add_invoice_to_stock 4960 4843 117 2.35 53 188752 25276 0 117
sp_cancel_adding_invoice 5631 5631 0 0.00 8 94887 1223 0 0
sp_cancel_client_order 1680 1680 0 0.00 6 9079 444 0 0
sp_cancel_customer_reserve 550453 550453 0 0.00 1 49185 114 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 3 1562 114 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 4 5386 163 0 0
sp_cancel_supplier_invoice 6181 6172 9 0.14 4 15882 1045 0 9
sp_cancel_supplier_order 422 422 0 0.00 10 1414252 186527 0 0
sp_cancel_write_off 9115 9115 0 0.00 2 10268 166 0 0
sp_client_order 19811 19811 0 0.00 6 15290 465 0 0
sp_customer_reserve 447267 447267 0 0.00 1 56601 240 0 0
sp_fill_shopping_cart 30200 30200 0 0.00 1 15756 83 0 0
sp_fill_shopping_cart_clo_res 447234 447234 0 0.00 0 29494 20 0 0
sp_get_clo_for_invoice 4803 4803 0 0.00 0 33924 319 0 0
sp_kill_cost_storno 15824 15824 0 0.00 0 35160 81 0 0
sp_kill_qstorno_ret_qs2qd 1116615 1116615 0 0.00 0 49176 50 0 0
sp_kill_qty_storno 587749 587749 0 0.00 0 49180 113 0 0
sp_lock_selected_doc 19781 19781 0 0.00 0 6797 7 0 0
sp_make_cost_storno 26826 26826 0 0.00 0 19457 92 0 0
sp_multiply_rows_for_pdistr 26822 26822 0 0.00 0 10288 38 0 0
sp_multiply_rows_for_qdistr 19811 19811 0 0.00 0 14203 171 0 0
sp_pay_from_customer 6850 6850 0 0.00 5 16165 194 0 0
sp_pay_to_supplier 5695 5695 0 0.00 7 12644 207 0 0
sp_payment_common 12545 12545 0 0.00 2 10252 47 0 0
sp_qd_handle_on_cancel_clo 1680 1680 0 0.00 1 9058 365 0 0
sp_qd_handle_on_invoice_upd_sts 10431 10431 0 0.00 0 14900 566 0 0
sp_qd_handle_on_reserve_upd_sts 9476 9476 0 0.00 0 15019 72 0 0
sp_reserve_write_off 9479 9479 0 0.00 8 15106 293 0 0
sp_supplier_invoice 5231 5231 0 0.00 9 78762 3029 0 0
sp_supplier_order 5171 5171 0 0.00 8 73018 1437 0 0
srv_aggregate_perf_data 98 98 0 0.00 2660 181593 18651 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 942 133994 35127 0 0
srv_find_qd_qs_mism 1034097 1034097 0 0.00 0 23591 9 0 0
srv_make_invnt_saldo 401 401 0 0.00 56 93649 5146 0 0
srv_make_money_saldo 852 852 0 0.00 1 13778 406 0 0
srv_recalc_idx_stat 4 4 0 0.00 95687 588295 302880 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4851 4851 0 0.00 0 17415 963 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 1 7073 445 0 0
t$perf-norm:sp_cancel_client_order 1680 1680 0 0.00 0 901 25 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 1634 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 1695 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 4599 35 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 729 37 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 1 121493 7681 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 1725 22 0 0
t$perf-norm:sp_client_order 19811 19811 0 0.00 0 7069 39 0 0
t$perf-norm:sp_customer_reserve 930 930 0 0.00 0 2509 19 0 0
t$perf-norm:sp_pay_from_customer 6850 6850 0 0.00 0 4380 20 0 0
t$perf-norm:sp_pay_to_supplier 5695 5695 0 0.00 0 3325 21 0 0
t$perf-norm:sp_reserve_write_off 9480 9480 0 0.00 0 16689 23 0 0
t$perf-norm:sp_supplier_invoice 5231 5231 0 0.00 0 4876 29 0 0
t$perf-norm:sp_supplier_order 5171 5171 0 0.00 0 4649 34 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 603 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 173 8 0 0
t$perf-norm:srv_make_invnt_saldo 401 401 0 0.00 0 2422 34 0 0
t$perf-norm:srv_make_money_saldo 852 852 0 0.00 0 372 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 25 183 85 0 0
v_add_invoice_to_stock 9830 9596 234 2.38 0 3842 24 0 234
v_all_customers 36562 36562 0 0.00 0 157 4 0 0
v_all_suppliers 29206 29206 0 0.00 0 190 4 0 0
v_all_wares 39614 39614 0 0.00 0 80 4 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 3794 18 0 0
v_cancel_client_order 3360 3360 0 0.00 0 3671 11 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 1550 11 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 2937 12 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 1609 14 0 0
v_cancel_supplier_invoice 750 732 18 2.40 0 2810 26 0 18
v_cancel_supplier_order 844 844 0 0.00 0 1415 14 0 0
v_cancel_write_off 1872 1872 0 0.00 0 3597 24 0 0
v_max_id_clo_ord 5167 5167 0 0.00 0 793 5 0 0
v_max_id_clo_res 930 930 0 0.00 0 4262 19 0 0
v_max_id_ord_sup 5222 5222 0 0.00 0 3539 6 0 0
v_max_non_paid_invoice 1478 1478 0 0.00 0 8875 13 0 0
v_max_non_paid_realizn 3434 3434 0 0.00 0 1634 8 0 0
v_min_id_clo_ord 5167 5167 0 0.00 0 7507 10 0 0
v_min_id_clo_res 930 930 0 0.00 0 5379 16 0 0
v_min_id_ord_sup 5221 5221 0 0.00 0 5466 8 0 0
v_min_non_paid_invoice 5695 5695 0 0.00 0 8151 15 0 0
v_min_non_paid_realizn 6850 6850 0 0.00 0 10214 20 0 0
v_our_firm 9884 9884 0 0.00 0 75 4 0 0
v_reserve_write_off 18952 18952 0 0.00 0 3676 11 0 0
x_lock_depdocs_on_canc_invoice 11437 11437 0 0.00 0 7210 122 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 6713 348 0 0
x_make_qstorno_1000_1200 5171 5171 0 0.00 3 72929 1254 0 0
x_make_qstorno_1200_2000 5231 5231 0 0.00 4 78754 2814 0 0
x_make_qstorno_2100_3300 447267 447267 0 0.00 0 53470 207 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-04-2022 04:38:14 to 08-04-2022 04:38:18.

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
02:07:18 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:19 29652 8192000000 9728006240 10027376640 806665152 8.964 100 94 188 0 836755872 7756880 22333840
02:09:50 30443 8192000000 9761378256 10068328448 833813328 9.238 100 91 181 0 873102464 17739424 21549712
02:11:30 16705 8192000000 9811507232 10131386368 871576864 9.616 100 91 180 0 917381408 24687456 21117088
02:12:54 11230 8192000000 9882845024 10218315776 924199424 10.137 100 78 155 0 988371296 47677440 16494432
02:14:09 8495 8192000000 9922774400 10270298112 963371760 10.522 100 86 165 1 1040784368 60122944 17289664
02:15:21 1410 8192000000 9957543744 10310131712 997083968 10.850 100 88 175 1 1077960304 63099200 17777136
02:16:25 1169 8192000000 9988802768 10351099904 1017302848 11.046 100 95 183 0 1108100992 71953440 18844704
02:17:28 1033 8192000000 10015482080 10382974976 1039453376 11.259 100 98 192 2 1134330432 74862048 20015008
02:18:31 1294 8192000000 9998982256 10378002432 1056730496 11.425 100 95 177 1 1125154368 50370848 18053024
02:19:33 1178 8192000000 10036626032 10413273088 1061955088 11.475 100 95 186 0 1160457536 79792608 18709840
02:20:36 1732 8192000000 10033009072 10418970624 1066835696 11.522 100 79 158 2 1151427936 68438064 16154176
02:21:39 1172 8192000000 10072473440 10459758592 1072515920 11.576 100 88 176 1 1193247344 103031648 17699776
02:22:47 4110 8192000000 10033694416 10431676416 1079104016 11.639 100 64 124 1 1155458880 64177472 12177392
02:23:53 4722 8192000000 10029771888 10419068928 1087959136 11.723 100 69 125 5 1150823648 50512000 12352512
02:25:00 1192 8192000000 10052598944 10441482240 1088153184 11.725 100 79 149 4 1173901728 72062960 13685584
02:26:03 1414 8192000000 10073476096 10460844032 1093850704 11.779 100 99 192 1 1196521056 82794512 19875840
02:27:05 1211 8192000000 10046334048 10434363392 1094948656 11.790 100 93 181 1 1169274880 57401392 16924832
02:28:08 1373 8192000000 10073011856 10466619392 1100913200 11.846 100 99 192 0 1195239776 75840272 18486304
02:29:10 10659 8192000000 10037590784 10433339392 1089161424 11.735 100 58 116 1 1185733488 85296032 11276032
02:30:22 1123 8192000000 10041981280 10426724352 1089586240 11.739 100 91 181 2 1164376912 57242608 17548064
02:31:26 1221 8192000000 10078922848 10462728192 1084749488 11.693 100 97 193 1 1195296784 91158864 19388432
02:32:28 1690 8192000000 10011473376 10398216192 1054248288 11.401 99 76 146 0 1131340480 61699296 15392896
02:33:31 1359 8192000000 10030690672 10408058880 1065098464 11.505 100 95 166 3 1154508592 72985584 16424544
02:34:34 1195 8192000000 10004229072 10382696448 1045453872 11.317 100 92 164 4 1118484192 56135408 16894912
02:35:35 1661 8192000000 9975259312 10346844160 1030128592 11.170 99 82 157 1 1095593920 50146896 15318432
02:36:38 1832 8192000000 9999453504 10380886016 1047225440 11.334 100 84 153 1 1126486576 64884608 14376528
02:37:47 3614 8192000000 10003686208 10375618560 1052835488 11.388 100 80 143 1 1115147616 48395200 13916928
02:38:58 9355 8192000000 10023708096 10408939520 1079131152 11.639 100 66 122 0 1153957456 62770752 12055552
02:40:11 1017 8192000000 10076162080 10454757376 1091193104 11.754 100 93 180 1 1194301104 84840176 18267824
02:41:14 2461 8192000000 10086036560 10468179968 1100389856 11.841 100 79 155 0 1201548896 85488928 15670112
02:42:21 5864 8192000000 10146808928 10539839488 1117143888 12.000 100 73 145 2 1264570608 132247120 15179600
02:43:43 2364 8192000000 10161495568 10554638336 1130045712 12.122 100 87 167 1 1279771728 131685664 18040352
02:44:48 10970 8192000000 10223713664 10613993472 1141149168 12.226 100 78 148 0 1332697232 175245552 16302512
02:46:07 1369 8192000000 10267509536 10666475520 1164317024 12.444 100 90 172 1 1377712416 194728128 18667264
02:47:17 2446 8192000000 10282576384 10683973632 1177498064 12.567 100 74 144 0 1399930288 206127296 16304928
02:48:25 1642 8192000000 10356070640 10752929792 1182615904 12.615 100 85 163 0 1465909808 264817408 18476496
02:49:31 1245 8192000000 10399415632 10805694464 1191387072 12.696 100 88 174 0 1509500016 297403376 20709568
02:50:38 930 8192000000 10461920272 10865152000 1199094320 12.768 100 97 194 1 1567463696 344951296 23418080
02:51:42 1648 8192000000 10489026496 10895699968 1203094688 12.805 100 95 188 2 1597662112 372298256 22269168
02:52:49 1671 8192000000 10512560256 10932887552 1203636400 12.810 100 95 186 0 1620660144 395293200 21730544
02:53:56 1202 8192000000 10536666752 10967154688 1208336560 12.854 100 94 187 2 1647090880 415116704 23637616
02:55:01 1503 8192000000 10561684848 10994368512 1206347648 12.835 100 92 182 0 1673668064 444561888 22758528
02:56:07 1537 8192000000 10527634144 10973442048 1190435008 12.687 99 93 182 1 1633457840 420631024 22391808
02:57:14 2442 8192000000 10524124032 10973773824 1197869360 12.757 100 87 170 0 1634919968 416931216 20119392
02:58:19 1063 8192000000 10523442208 10960678912 1188657232 12.671 100 95 189 0 1627247008 416416784 22172992
02:59:22 1110 8192000000 10510944720 10947682304 1194324896 12.724 100 91 175 0 1615096944 401059088 19712960
03:00:27 1001 8192000000 10553910592 11003789312 1205457344 12.827 100 91 182 1 1656842544 430076304 21308896
03:01:30 7607 8192000000 10479375296 10946473984 1197410432 12.752 100 54 98 1 1591088752 379927280 13751040
03:02:43 12122 8192000000 10520647984 10959450112 1179958192 12.590 99 46 90 0 1613754848 422259552 11537104
03:03:59 14513 8192000000 10600737744 11051700224 1199645920 12.773 100 47 94 0 1704918400 492946320 12326160
03:05:17 16698 8192000000 10494089744 11003760640 1185824960 12.644 100 36 71 0 1620302544 424679024 9798560
03:06:37 1463 8192000000 10411832608 10902540288 1168542576 12.483 99 76 144 0 1508909376 323333136 17033664
03:07:40 8094 8192000000 10421917872 10860494848 1171751872 12.513 100 40 80 0 1543293984 361039456 10502656
03:08:50 13124 8192000000 10312271920 10801864704 1160555824 12.408 99 37 74 1 1427790832 257599936 9635072
03:10:08 14857 8192000000 10268070224 10738733056 1150476864 12.314 99 40 80 0 1378670848 218904320 9289664
03:11:24 44409 8192000000 10288373600 10777780224 1158798880 12.392 100 22 44 0 1395513920 231081584 5633456
03:13:18 42954 8192000000 10170687680 10628718592 1135498592 12.173 98 46 83 0 1295758416 149570448 10689376
03:15:04 37016 8192000000 10146045552 10636808192 1129312016 12.115 99 19 36 2 1269694784 136157696 4225072
03:18:01 96088 8192000000 10068241232 10513653760 1096300912 11.803 97 18 36 0 1127194816 27670832 3223072
03:21:18 67221 8192000000 10058475616 10495983616 1107169680 11.906 98 34 66 3 1140245552 25961920 7113952
03:23:29 90633 8192000000 10051290448 10498101248 1076419664 11.613 95 9 18 1 1104696192 26945280 1331248
03:25:59 23259 8192000000 10100552000 10540683264 1135098528 12.169 100 36 67 0 1197860384 55986480 6775376
03:27:27 42497 8192000000 10111067408 10548490240 1112296736 11.954 99 9 18 0 1190140016 75329984 2513296
03:29:11 64837 8192000000 10082097520 10537013248 1089777040 11.741 97 12 23 3 1151405120 58914528 2713552
03:31:17 132116 8192000000 10054574016 10500820992 1089451456 11.737 97 26 52 0 1137911520 43195712 5264352
03:34:32 54911 8192000000 10072465456 10502242304 1113859888 11.969 99 40 77 0 1162899408 40613056 8426464
03:36:29 38857 8192000000 10131142560 10576498688 1137560608 12.193 100 41 82 2 1239228768 92927072 8741088
03:38:28 41184 8192000000 10147472800 10591002624 1123143376 12.057 98 39 76 0 1227956928 96505440 8308112
03:40:10 86229 8192000000 10079429584 10542231552 1146257360 12.274 100 15 30 1 1204249232 54541440 3450432
03:43:04 46085 8192000000 10079654032 10542043136 1121989296 12.046 99 14 28 1 1167101456 41803184 3308976
03:45:44 124465 8192000000 10112755728 10549940224 1146674576 12.278 100 9 18 1 1186230048 37679600 1875872
03:48:49 62153 8192000000 10132272912 10592419840 1118540496 12.013 98 27 54 1 1224837424 100380816 5916112
03:51:31 46533 8192000000 10094557344 10523496448 1109183664 11.925 98 19 36 1 1147927456 35147136 3596656
03:53:20 57022 8192000000 10153145184 10622976000 1139897280 12.215 100 24 48 2 1242435888 97407712 5130896
03:55:34 79867 8192000000 10109103824 10602680320 1118935232 12.017 98 17 34 0 1179792224 56997968 3859024
03:57:54 33402 8192000000 10080915600 10521128960 1119377584 12.021 99 42 84 0 1169314048 41408768 8527696
03:59:29 133992 8192000000 10092172464 10521927680 1112656448 11.958 98 39 76 1 1194687392 74372432 7658512
04:03:09 87720 8192000000 10120601184 10588626944 1120787024 12.034 98 26 52 2 1197652752 71535360 5330368
04:05:43 58782 8192000000 10062743424 10540371968 1105545248 11.890 98 14 28 0 1174159888 65417744 3196896
04:07:57 51438 8192000000 10056471152 10510536704 1122894448 12.054 99 8 16 1 1167625664 43446080 1285136
04:09:50 54565 8192000000 10044368816 10473459712 1108906368 11.922 98 30 52 2 1128328976 13318576 6104032
04:11:48 35614 8192000000 10103619728 10546987008 1133628000 12.156 100 27 52 3 1210295456 70980208 5687248
04:13:29 15498 8192000000 10164714624 10599923712 1135546592 12.174 99 45 88 0 1256567104 110832240 10188272
04:14:45 29513 8192000000 10164186720 10623209472 1133714544 12.156 99 33 66 2 1255256192 113880368 7661280
04:16:17 20621 8192000000 10188916304 10623971328 1142727648 12.241 99 42 84 2 1275734944 123802448 9204848
04:17:41 21557 8192000000 10239696080 10680532992 1153711728 12.344 100 38 76 0 1321337520 158748080 8877712
04:19:08 30504 8192000000 10257405616 10697691136 1149136192 12.301 99 36 73 0 1351007264 193220832 8650240
04:20:40 21988 8192000000 10273861072 10724012032 1138116880 12.198 98 41 80 0 1359945776 211914608 9914288
04:22:02 30793 8192000000 10285953680 10732515328 1149422960 12.304 100 30 58 2 1379223392 222465136 7335296
04:23:35 28169 8192000000 10229838800 10738528256 1118640944 12.014 97 20 40 1 1310314752 186842880 4830928
04:25:03 29664 8192000000 10166427472 10647797760 1098163232 11.820 97 29 58 1 1228731856 124029488 6539136
04:26:33 82709 8192000000 10080665840 10604937216 1106650128 11.901 98 15 30 0 1185734704 75746256 3338320
04:29:39 73240 8192000000 10060643776 10496430080 1103037200 11.866 98 22 40 0 1129679536 22117984 4524352
04:31:58 95251 8192000000 10064076592 10513096704 1113949840 11.970 98 27 52 1 1176463040 56933888 5579312
04:34:36 74548 8192000000 10094783648 10564796416 1140157840 12.217 100 20 38 1 1213005648 68844112 4003696
04:36:57 25777 8192000000 9000563936 9217810432 153719536 1.841 14 2 4 1 172134960 18047040 368384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-04-2022 04:38:20 to 08-04-2022 04:38:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-04-2022 04:38:37 to 08-04-2022 04:38:39.

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          213589
MON$OLDEST_ACTIVE               213590
MON$OLDEST_SNAPSHOT             213590
MON$NEXT_TRANSACTION            213590
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                       2097664
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2750 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2750 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2750 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2750 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 ---
:85: 
:86: mscs-test-161.budzdorov.ru    Fri Apr  8 04:37:25 2022
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:88: 
--- end of diff output ---

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

08.04.22 04:38:40. 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-08 04:38:42 No severe PSQL-related problems occured
08.04.22 04:38:44. End of report.