Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.10.2022 01:41
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 15747620 kB
MemAvailable 30601820 kB
Buffers 0 kB
Cached 16004004 kB
SwapCached 2088 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-10-02 01:38:24
DTS_END                         2022-10-02 01:38:24.9760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44532
TRN_ID                          243731
ATT_ID                          705
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: 9156.83 23379 1098819
customer order: creation 174.17 381 20900
customer order: refuse 14.74 337 1769
order to supplier: creation 45.60 1288 5472
order to supplier: removal 3.38 99128 405
invoice (draft): creation 46.29 3261 5555
invoice (draft): removal 53.78 762 6454
invoice accept: apply 40.77 17955 4892
invoice accept: cancel 47.86 749 5743
customer reserve: creation 3762.78 179 451534
customer reserve: removal 4668.62 56 560234
realization accept: apply 83.05 174 9966
realization accept: cancel 85.54 111 10265
payment from customer: creation 60.05 123 7206
payment from customer: removal 5.82 76 698
payment to supplier: creation 48.45 129 5814
payment to supplier: removal 3.61 82 433
service: total inventory turnovers 4.68 1139 562
service: total monetary turnovers 6.76 64 811
service: aggregate perf. data 0.85 21743 102
service: refresh index statistics 0.03 319846 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-10-2022 01:41:28 to 02-10-2022 01:41:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4875
TEST_TIME 2 4863
TEST_TIME 3 3844
TEST_TIME 4 3603
TEST_TIME 5 4085
TEST_TIME 6 3857
TEST_TIME 7 4508
TEST_TIME 8 4834
TEST_TIME 9 4826
TEST_TIME 10 4793
TEST_TIME 11 4834
TEST_TIME 12 5115
TEST_TIME 13 5344
TEST_TIME 14 5922
TEST_TIME 15 6094
TEST_TIME 16 6473
TEST_TIME 17 6464
TEST_TIME 18 7356
TEST_TIME 19 7470
TEST_TIME 20 8057
TEST_TIME 21 8234
TEST_TIME 22 9110
TEST_TIME 23 9430
TEST_TIME 24 10237
TEST_TIME 25 10018
TEST_TIME 26 9783
TEST_TIME 27 9614
TEST_TIME 28 9786
TEST_TIME 29 9681
TEST_TIME 30 9552
TEST_TIME 31 9431
TEST_TIME 32 9337
TEST_TIME 33 9471
TEST_TIME 34 9395
TEST_TIME 35 9251
TEST_TIME 36 9188
TEST_TIME 37 9162
TEST_TIME 38 9305
TEST_TIME 39 9360
TEST_TIME 40 9439
TEST_TIME 41 9392
TEST_TIME 42 9417
TEST_TIME 43 9489
TEST_TIME 44 9547
TEST_TIME 45 9631
TEST_TIME 46 9730
TEST_TIME 47 9877
TEST_TIME 48 9810
TEST_TIME 49 9924
TEST_TIME 50 9903
TEST_TIME 51 9916
TEST_TIME 52 9743
TEST_TIME 53 9725
TEST_TIME 54 9616
TEST_TIME 55 9652
TEST_TIME 56 9662
TEST_TIME 57 9593
TEST_TIME 58 9517
TEST_TIME 59 9511
TEST_TIME 60 9422
TEST_TIME 61 9411
TEST_TIME 62 9451
TEST_TIME 63 9407
TEST_TIME 64 9404
TEST_TIME 65 9407
TEST_TIME 66 9470
TEST_TIME 67 9477
TEST_TIME 68 9516
TEST_TIME 69 9497
TEST_TIME 70 9500
TEST_TIME 71 9480
TEST_TIME 72 9531
TEST_TIME 73 9586
TEST_TIME 74 9584
TEST_TIME 75 9529
TEST_TIME 76 9603
TEST_TIME 77 9499
TEST_TIME 78 9485
TEST_TIME 79 9412
TEST_TIME 80 9380
TEST_TIME 81 9361
TEST_TIME 82 9299
TEST_TIME 83 9312
TEST_TIME 84 9338
TEST_TIME 85 9290
TEST_TIME 86 9274
TEST_TIME 87 9302
TEST_TIME 88 9303
TEST_TIME 89 9318
TEST_TIME 90 9372
TEST_TIME 91 9379
TEST_TIME 92 9364
TEST_TIME 93 9375
TEST_TIME 94 9425
TEST_TIME 95 9418
TEST_TIME 96 9467
TEST_TIME 97 9483
TEST_TIME 98 9422
TEST_TIME 99 9391
TEST_TIME 100 9374
TEST_TIME 101 9365
TEST_TIME 102 9312
TEST_TIME 103 9282
TEST_TIME 104 9230
TEST_TIME 105 9211
TEST_TIME 106 9215
TEST_TIME 107 9214
TEST_TIME 108 9225
TEST_TIME 109 9220
TEST_TIME 110 9223
TEST_TIME 111 9222
TEST_TIME 112 9212
TEST_TIME 113 9294
TEST_TIME 114 9269
TEST_TIME 115 9270
TEST_TIME 116 9291
TEST_TIME 117 9302
TEST_TIME 118 9292
TEST_TIME 119 9229
TEST_TIME 120 9163

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-10-2022 01:41:32 to 02-10-2022 01:41:35.

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 1518051 1518051 0 0.00 0 19652 6 0 0
doc_list_biud 1518051 1518051 0 0.00 0 28972 26 0 0
sp_add_invoice_to_stock 5023 4892 131 2.60 42 158530 17955 0 131
sp_cancel_adding_invoice 5743 5743 0 0.00 5 62032 750 0 0
sp_cancel_client_order 1769 1769 0 0.00 4 14923 337 0 0
sp_cancel_customer_reserve 560234 560234 0 0.00 1 28975 56 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 4614 76 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 5457 82 0 0
sp_cancel_supplier_invoice 6469 6454 15 0.23 1 23268 763 0 15
sp_cancel_supplier_order 405 405 0 0.00 14 1130251 99128 0 0
sp_cancel_write_off 10265 10265 0 0.00 1 15888 112 0 0
sp_client_order 20900 20900 0 0.00 3 28363 381 0 0
sp_customer_reserve 451534 451534 0 0.00 0 82222 179 0 0
sp_fill_shopping_cart 31924 31924 0 0.00 1 13677 36 0 0
sp_fill_shopping_cart_clo_res 451527 451527 0 0.00 0 17406 15 0 0
sp_get_clo_for_invoice 4885 4885 0 0.00 0 18550 288 0 0
sp_kill_cost_storno 17139 17139 0 0.00 0 12969 49 0 0
sp_kill_qstorno_ret_qs2qd 1137592 1137592 0 0.00 0 28957 31 0 0
sp_kill_qty_storno 599721 599721 0 0.00 0 28972 66 0 0
sp_lock_selected_doc 20652 20652 0 0.00 0 8494 7 0 0
sp_make_cost_storno 27871 27871 0 0.00 0 19629 53 0 0
sp_multiply_rows_for_pdistr 27871 27871 0 0.00 0 7127 25 0 0
sp_multiply_rows_for_qdistr 20900 20900 0 0.00 0 13085 183 0 0
sp_pay_from_customer 7206 7206 0 0.00 4 25262 124 0 0
sp_pay_to_supplier 5814 5814 0 0.00 4 15690 130 0 0
sp_payment_common 13020 13020 0 0.00 1 25201 36 0 0
sp_qd_handle_on_cancel_clo 1769 1769 0 0.00 0 14914 306 0 0
sp_qd_handle_on_invoice_upd_sts 10628 10628 0 0.00 0 22211 304 0 0
sp_qd_handle_on_reserve_upd_sts 9966 9966 0 0.00 0 17537 56 0 0
sp_reserve_write_off 9966 9966 0 0.00 6 18702 175 0 0
sp_supplier_invoice 5555 5555 0 0.00 6 88675 3261 0 0
sp_supplier_order 5472 5472 0 0.00 5 44654 1288 0 0
srv_aggregate_perf_data 102 102 0 0.00 2621 287109 21744 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1265 153569 47612 0 0
srv_find_qd_qs_mism 1051815 1051815 0 0.00 0 16342 6 0 0
srv_make_invnt_saldo 562 562 0 0.00 28 143423 1139 0 0
srv_make_money_saldo 811 811 0 0.00 1 23529 64 0 0
srv_recalc_idx_stat 4 4 0 0.00 220968 458978 319846 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4892 4892 0 0.00 0 21480 172 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 3222 82 0 0
t$perf-norm:sp_cancel_client_order 1769 1769 0 0.00 0 3147 19 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 3333 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 8680 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 7571 28 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 406 7 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 28911 1700 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 2224 9 0 0
t$perf-norm:sp_client_order 20900 20900 0 0.00 0 16017 19 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 5897 16 0 0
t$perf-norm:sp_pay_from_customer 7206 7206 0 0.00 0 3440 8 0 0
t$perf-norm:sp_pay_to_supplier 5814 5814 0 0.00 0 22069 25 0 0
t$perf-norm:sp_reserve_write_off 9966 9966 0 0.00 0 10491 13 0 0
t$perf-norm:sp_supplier_invoice 5555 5555 0 0.00 0 12803 20 0 0
t$perf-norm:sp_supplier_order 5472 5472 0 0.00 0 21993 18 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 59 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 332 11 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 3383 24 0 0
t$perf-norm:srv_make_money_saldo 811 811 0 0.00 0 2599 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10032 9770 262 2.61 0 17579 19 0 262
v_all_customers 38216 38216 0 0.00 0 163 0 0 0
v_all_suppliers 30420 30420 0 0.00 0 162 1 0 0
v_all_wares 41800 41800 0 0.00 0 75 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1915 11 0 0
v_cancel_client_order 3538 3538 0 0.00 0 2758 4 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 825 4 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 2463 6 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 5443 14 0 0
v_cancel_supplier_invoice 814 784 30 3.68 0 2561 18 0 30
v_cancel_supplier_order 810 810 0 0.00 0 2918 7 0 0
v_cancel_write_off 2010 2010 0 0.00 0 3659 14 0 0
v_max_id_clo_ord 5472 5472 0 0.00 0 558 1 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 9965 32 0 0
v_max_id_ord_sup 5552 5552 0 0.00 0 3510 4 0 0
v_max_non_paid_invoice 1628 1628 0 0.00 0 2985 5 0 0
v_max_non_paid_realizn 3849 3849 0 0.00 0 7819 6 0 0
v_min_id_clo_ord 5472 5472 0 0.00 0 3799 6 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 5676 18 0 0
v_min_id_ord_sup 5552 5552 0 0.00 0 5928 8 0 0
v_min_non_paid_invoice 5814 5814 0 0.00 0 8422 21 0 0
v_min_non_paid_realizn 7206 7206 0 0.00 0 25199 32 0 0
v_our_firm 10298 10298 0 0.00 0 12 1 0 0
v_reserve_write_off 19932 19932 0 0.00 0 3389 5 0 0
x_lock_depdocs_on_canc_invoice 11805 11805 0 0.00 0 19207 119 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 6049 311 0 0
x_make_qstorno_1000_1200 5472 5472 0 0.00 1 44645 1224 0 0
x_make_qstorno_1200_2000 5555 5555 0 0.00 3 88668 3122 0 0
x_make_qstorno_2100_3300 451534 451534 0 0.00 0 82220 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-10-2022 01:41:36 to 02-10-2022 01:41:40.

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
23:08:28 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:29 20923 8192000000 9917370848 10227331072 1000868384 10.887 100 94 188 0 1031531344 6305264 24357696
23:10:57 21684 8192000000 9947405264 10267656192 1029199104 11.161 100 92 184 0 1061363056 8420192 23743760
23:12:22 11812 8192000000 9981295872 10314584064 1063433520 11.489 100 82 164 0 1093354960 9881264 20040176
23:13:45 9844 8192000000 10022542448 10373709824 1117567232 12.004 100 75 148 0 1146042112 11030816 17444064
23:14:58 8788 8192000000 10084354800 10443837440 1164295520 12.443 100 71 140 0 1192329072 11733648 16299904
23:16:09 2965 8192000000 10128348336 10497794048 1220596560 12.967 100 68 134 3 1245991312 10879344 14515408
23:17:13 1776 8192000000 10171196592 10545426432 1256708432 13.300 100 85 169 0 1288175680 13403184 18064064
23:18:19 1004 8192000000 10184309440 10564591616 1276724816 13.483 100 83 162 1 1308312208 13882672 17704720
23:19:23 747 8192000000 10223200080 10608025600 1301607424 13.710 100 92 183 1 1335991680 14306016 20078240
23:20:25 22640 8192000000 10210911760 10613788672 1312545712 13.809 100 59 118 0 1334226080 8343040 13337328
23:21:48 5326 8192000000 10205382800 10620616704 1316837376 13.848 100 30 61 1 1330436464 6852640 6746448
23:22:56 6317 8192000000 10211918544 10628317184 1323996528 13.913 100 19 39 1 1334679440 6242304 4440608
23:24:05 12231 8192000000 10211232080 10633797632 1329240880 13.960 100 15 28 1 1338303248 6164608 2897760
23:25:17 61888 8192000000 10225298512 10650251264 1335019744 14.012 100 29 57 2 1347415536 5184256 7211536
23:27:20 2804 8192000000 10229315984 10651561984 1338142432 14.041 100 44 87 1 1358701632 9823808 10735392
23:28:26 4930 8192000000 10211695888 10629685248 1318070592 13.859 99 22 44 0 1328938672 6093184 4774896
23:29:33 52048 8192000000 10165434432 10565455872 1242002816 13.165 96 36 72 1 1255951296 4917904 9030576
23:31:31 7494 8192000000 10128787680 10525102080 1216992800 12.934 98 34 68 0 1231285536 5734784 8557952
23:32:40 9073 8192000000 10136591232 10535485440 1220439840 12.966 97 20 41 0 1231163472 5899728 4823904
23:33:49 6684 8192000000 10159143152 10567643136 1272541488 13.445 100 11 23 0 1280081840 4892400 2647952
23:34:57 25436 8192000000 10194144352 10590453760 1277301808 13.488 99 50 98 1 1293536000 5445456 10788736
23:36:23 6457 8192000000 10195257392 10608758784 1305531968 13.746 100 32 64 0 1318627504 5092608 8002928
23:37:32 10763 8192000000 10205810448 10620465152 1313459152 13.817 100 14 28 0 1321453264 4566576 3427536
23:38:47 25220 8192000000 10244111584 10647728128 1337629200 14.036 100 52 103 1 1355013312 5766816 11617296
23:40:17 52784 8192000000 10241977296 10667548672 1351293504 14.159 100 34 66 1 1367233200 8288832 7650864
23:43:00 19189 8192000000 10269620080 10694967296 1367289104 14.303 100 39 78 1 1391954848 14634672 10031072
23:44:31 22580 8192000000 10302004976 10723508224 1385865696 14.469 100 41 82 0 1415280160 19093696 10320768
23:46:02 6285 8192000000 10325091600 10743218176 1402276976 14.615 100 65 127 2 1437855808 20018832 15560000
23:47:17 11650 8192000000 10344341840 10768478208 1420358288 14.776 100 49 97 0 1453111728 21138752 11614688
23:48:33 5020 8192000000 10361405968 10778341376 1431445872 14.874 100 65 123 0 1469661136 23678464 14536800
23:49:43 20945 8192000000 10348413856 10769747968 1402886464 14.621 98 63 123 0 1450113088 31661712 15564912
23:51:27 4733 8192000000 10371023696 10795536384 1426673312 14.832 100 63 126 1 1478840560 36535712 15631536
23:52:37 4608 8192000000 10355441856 10780672000 1403827296 14.629 99 61 118 1 1456855280 36824272 16203712
23:53:44 2384 8192000000 10364989776 10774093824 1408239296 14.668 99 88 174 0 1468680160 38598672 21842192
23:54:52 2575 8192000000 10347838928 10755612672 1386254112 14.472 99 81 160 0 1451380080 44398112 20727856
23:55:59 3250 8192000000 10355123984 10769551360 1371744400 14.343 98 82 162 0 1443440176 50298512 21397264
23:57:06 7386 8192000000 10313017328 10743099392 1356377248 14.205 98 43 85 0 1409134512 41198384 11558880
23:58:23 1265 8192000000 10334656624 10744160256 1373863840 14.362 100 72 142 5 1437326160 43748800 19713520
23:59:27 10958 8192000000 10305311280 10730942464 1361241600 14.249 100 34 67 0 1421889088 51559552 9087936
00:00:46 30332 8192000000 10281896816 10722324480 1328789664 13.956 97 24 48 1 1374629952 39080400 6759888
00:02:21 41278 8192000000 10259783680 10711363584 1332922192 13.994 99 13 26 1 1369267920 32677472 3668256
00:04:03 115714 8192000000 10240862784 10677211136 1299757344 13.693 96 31 61 2 1317652912 11091312 6804256
00:07:00 87901 8192000000 10253525792 10689687552 1320257568 13.879 97 26 51 0 1334624176 8850304 5516304
00:09:29 24505 8192000000 10288342080 10717896704 1358159088 14.221 99 41 82 0 1385863648 18154672 9549888
00:10:56 40423 8192000000 10278430880 10738245632 1374962464 14.371 100 17 34 0 1398307344 19532064 3812816
00:12:47 123141 8192000000 10259689424 10693988352 1289574768 13.600 94 19 38 0 1307875296 14036576 4263952
00:15:50 26433 8192000000 10278590384 10721062912 1369885008 14.326 100 35 66 0 1392986560 15127968 7973584
00:17:29 88999 8192000000 10295220544 10751954944 1382183344 14.436 100 11 22 0 1399819648 15139856 2496448
00:20:00 58285 8192000000 10316383344 10771320832 1402846704 14.620 100 21 42 0 1427266128 19526736 4892688
00:22:03 37683 8192000000 10317679824 10765897728 1391305776 14.518 99 20 40 0 1404204976 8230176 4669024
00:24:53 63666 8192000000 10323137680 10785124352 1413378592 14.714 100 8 16 0 1435637088 20068048 2190448
00:26:56 39581 8192000000 10321753200 10765475840 1372973040 14.354 97 18 36 0 1392546784 15718032 3855712
00:29:10 109631 8192000000 10321609056 10759020544 1402137392 14.614 100 21 42 0 1419650672 12150688 5362592
00:32:06 46771 8192000000 10317813008 10774298624 1408742384 14.673 100 33 66 1 1428611744 12522336 7347024
00:33:56 23583 8192000000 10303519312 10762321920 1402727200 14.619 99 24 47 0 1416706560 8754208 5225152
00:35:33 40415 8192000000 10285872176 10739482624 1374733536 14.369 98 17 34 0 1388098448 9214064 4150848
00:37:14 39278 8192000000 10277452528 10736500736 1363160144 14.266 99 11 22 0 1381437184 15331360 2945680
00:39:16 72225 8192000000 10261102480 10713497600 1315917600 13.840 95 11 22 0 1329624800 11529120 2178080
00:41:31 42178 8192000000 10246500816 10696691712 1297330224 13.671 96 12 24 0 1312346640 12291152 2725264
00:43:20 71860 8192000000 10274794512 10738143232 1346618960 14.117 98 11 22 0 1364492080 15215184 2657936
00:45:34 36093 8192000000 10260086704 10700242944 1325025808 13.922 98 18 36 0 1337720992 8871072 3824112
00:47:11 67878 8192000000 10275595440 10714185728 1336452256 14.025 98 25 51 0 1351130912 9607264 5071392
00:49:30 26185 8192000000 10272860720 10718097408 1358994896 14.228 100 22 44 0 1376870880 12980096 4895888
00:51:00 65379 8192000000 10254398784 10698809344 1292572352 13.628 95 23 47 0 1308316560 10715616 5028592
00:53:07 24972 8192000000 10276413008 10712174592 1346587648 14.117 99 26 50 1 1360197088 7977696 5631744
00:54:37 126005 8192000000 10254256224 10707320832 1350051776 14.148 98 23 45 0 1366234720 11189536 4993408
00:57:47 34907 8192000000 10281715504 10730393600 1371926464 14.344 99 30 56 0 1388549728 10689360 5933904
00:59:35 73990 8192000000 10277944064 10737766400 1365500848 14.287 99 11 22 0 1381525360 13388352 2636160
01:01:57 65144 8192000000 10275934160 10746814464 1378988256 14.408 100 10 20 0 1395545920 13883408 2674256
01:04:02 44278 8192000000 10280800992 10727837696 1365252144 14.284 99 14 25 0 1377629840 9093616 3284080
01:05:52 34438 8192000000 10287568384 10734612480 1380990992 14.425 100 31 62 1 1397321696 10191120 6139584
01:07:31 27942 8192000000 10286819888 10730774528 1343105744 14.085 97 25 50 1 1358092240 9331024 5655472
01:08:59 97102 8192000000 10288220144 10743541760 1340847008 14.065 97 5 10 0 1356734000 15242448 644544
01:11:43 153568 8192000000 10312572048 10767499264 1338044784 14.040 96 19 38 0 1355144256 13329312 3770160
01:15:23 61055 8192000000 10315926336 10763563008 1399698304 14.592 100 40 82 2 1428646496 19146176 9802016
01:17:26 56514 8192000000 10310556112 10767470592 1405684944 14.646 100 24 48 0 1422879712 11527536 5667232
01:19:29 55295 8192000000 10298773648 10766196736 1397843728 14.576 99 9 18 0 1409787696 10005808 1938160
01:21:25 50318 8192000000 10295858000 10756329472 1378648656 14.404 98 15 31 0 1389966224 7487008 3830560
01:23:17 64366 8192000000 10282110192 10752962560 1344401968 14.097 96 7 14 1 1356551712 10715856 1433888
01:25:24 63775 8192000000 10296374144 10762514432 1402222752 14.615 100 14 28 0 1419852592 13907280 3722560
01:27:35 44251 8192000000 10271612368 10722398208 1357810912 14.218 97 30 60 0 1375039952 10075376 7153664
01:29:26 34157 8192000000 10290949424 10739331072 1364859056 14.281 99 26 50 0 1383254448 12363040 6032352
01:31:06 82258 8192000000 10264169872 10716286976 1274412544 13.462 92 10 20 0 1291550032 14756800 2380688
01:33:29 55143 8192000000 10268576048 10711724032 1310667328 13.792 96 32 64 0 1329434048 11476128 7290592
01:35:25 41688 8192000000 10270211424 10705780736 1320310320 13.880 97 31 62 0 1341025312 13500880 7214112
01:37:09 87981 8192000000 9485892288 9811243008 634002896 7.183 46 32 64 0 648824192 7772560 7048736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 02-10-2022 01:41:42 to 02-10-2022 01:41:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-10-2022 01:41:58 to 02-10-2022 01:42:00.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          244094
MON$OLDEST_ACTIVE               244095
MON$OLDEST_SNAPSHOT             244095
MON$NEXT_TRANSACTION            244095
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-03 16:41:55.3470
MON$PAGES                       2009856
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:73: 
:74: mscs-test-161.budzdorov.ru    Sun Oct  2 01:39:15 2022
:75:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:76: 
--- end of diff output ---

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

02.10.22 01:42:02. There are 3 logs in /var/tmp/logs-oltp30 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-10-02 01:42:04 No severe PSQL-related problems occured
02.10.22 01:42:05. End of report.