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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15562432 kB
MemAvailable 30565500 kB
Buffers 0 kB
Cached 16181848 kB
SwapCached 2180 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-08 22:38:34
DTS_END                         2022-10-08 22:38:34.7260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37472
TRN_ID                          234559
ATT_ID                          707
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: 9172.31 19737 1100677
customer order: creation 170.38 359 20446
customer order: refuse 14.86 376 1783
order to supplier: creation 44.92 1249 5390
order to supplier: removal 3.69 92750 443
invoice (draft): creation 45.88 3470 5506
invoice (draft): removal 52.43 805 6291
invoice accept: apply 41.33 18046 4959
invoice accept: cancel 47.22 839 5666
customer reserve: creation 3776.34 180 453161
customer reserve: removal 4686.13 57 562336
realization accept: apply 81.26 168 9751
realization accept: cancel 80.68 114 9682
payment from customer: creation 57.83 109 6939
payment from customer: removal 5.89 71 707
payment to supplier: creation 48.50 125 5820
payment to supplier: removal 3.32 79 398
service: total inventory turnovers 4.30 1186 516
service: total monetary turnovers 6.48 37 777
service: aggregate perf. data 0.85 22926 102
service: refresh index statistics 0.03 251793 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-10-2022 22:40:22 to 08-10-2022 22:40:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2651
TEST_TIME 2 4209
TEST_TIME 3 4203
TEST_TIME 4 4377
TEST_TIME 5 5069
TEST_TIME 6 4850
TEST_TIME 7 5211
TEST_TIME 8 5216
TEST_TIME 9 5591
TEST_TIME 10 5976
TEST_TIME 11 6276
TEST_TIME 12 6591
TEST_TIME 13 6878
TEST_TIME 14 7076
TEST_TIME 15 7839
TEST_TIME 16 8041
TEST_TIME 17 8457
TEST_TIME 18 8792
TEST_TIME 19 8602
TEST_TIME 20 8650
TEST_TIME 21 8805
TEST_TIME 22 9170
TEST_TIME 23 9377
TEST_TIME 24 9589
TEST_TIME 25 9804
TEST_TIME 26 10276
TEST_TIME 27 10411
TEST_TIME 28 11196
TEST_TIME 29 11052
TEST_TIME 30 11156
TEST_TIME 31 11077
TEST_TIME 32 10872
TEST_TIME 33 10676
TEST_TIME 34 10599
TEST_TIME 35 10497
TEST_TIME 36 10356
TEST_TIME 37 10245
TEST_TIME 38 10081
TEST_TIME 39 9977
TEST_TIME 40 9819
TEST_TIME 41 10040
TEST_TIME 42 9885
TEST_TIME 43 9935
TEST_TIME 44 9867
TEST_TIME 45 9780
TEST_TIME 46 9717
TEST_TIME 47 9696
TEST_TIME 48 9811
TEST_TIME 49 9730
TEST_TIME 50 9563
TEST_TIME 51 9517
TEST_TIME 52 9423
TEST_TIME 53 9364
TEST_TIME 54 9308
TEST_TIME 55 9245
TEST_TIME 56 9275
TEST_TIME 57 9301
TEST_TIME 58 9266
TEST_TIME 59 9334
TEST_TIME 60 9344
TEST_TIME 61 9366
TEST_TIME 62 9493
TEST_TIME 63 9491
TEST_TIME 64 9473
TEST_TIME 65 9585
TEST_TIME 66 9607
TEST_TIME 67 9592
TEST_TIME 68 9546
TEST_TIME 69 9584
TEST_TIME 70 9670
TEST_TIME 71 9723
TEST_TIME 72 9675
TEST_TIME 73 9564
TEST_TIME 74 9504
TEST_TIME 75 9442
TEST_TIME 76 9480
TEST_TIME 77 9387
TEST_TIME 78 9326
TEST_TIME 79 9330
TEST_TIME 80 9276
TEST_TIME 81 9217
TEST_TIME 82 9204
TEST_TIME 83 9291
TEST_TIME 84 9272
TEST_TIME 85 9258
TEST_TIME 86 9307
TEST_TIME 87 9266
TEST_TIME 88 9288
TEST_TIME 89 9307
TEST_TIME 90 9445
TEST_TIME 91 9401
TEST_TIME 92 9474
TEST_TIME 93 9440
TEST_TIME 94 9477
TEST_TIME 95 9406
TEST_TIME 96 9343
TEST_TIME 97 9326
TEST_TIME 98 9323
TEST_TIME 99 9303
TEST_TIME 100 9236
TEST_TIME 101 9233
TEST_TIME 102 9193
TEST_TIME 103 9169
TEST_TIME 104 9152
TEST_TIME 105 9151
TEST_TIME 106 9107
TEST_TIME 107 9116
TEST_TIME 108 9147
TEST_TIME 109 9126
TEST_TIME 110 9143
TEST_TIME 111 9212
TEST_TIME 112 9232
TEST_TIME 113 9214
TEST_TIME 114 9200
TEST_TIME 115 9269
TEST_TIME 116 9272
TEST_TIME 117 9289
TEST_TIME 118 9240
TEST_TIME 119 9189
TEST_TIME 120 9178

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-10-2022 22:40:26 to 08-10-2022 22:40:29.

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 1520331 1520331 0 0.00 0 27350 6 0 0
doc_list_biud 1520328 1520328 0 0.00 0 48470 27 0 0
sp_add_invoice_to_stock 5036 4959 77 1.52 30 219962 18047 0 77
sp_cancel_adding_invoice 5666 5666 0 0.00 5 78264 839 0 0
sp_cancel_client_order 1783 1783 0 0.00 4 8329 377 0 0
sp_cancel_customer_reserve 562336 562336 0 0.00 0 48471 57 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 9600 72 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 5867 79 0 0
sp_cancel_supplier_invoice 6295 6291 4 0.06 1 26414 805 0 4
sp_cancel_supplier_order 443 443 0 0.00 7 992735 92751 0 0
sp_cancel_write_off 9682 9682 0 0.00 1 12330 114 0 0
sp_client_order 20446 20446 0 0.00 3 46302 359 0 0
sp_customer_reserve 453161 453161 0 0.00 0 88638 180 0 0
sp_fill_shopping_cart 31342 31342 0 0.00 1 16924 42 0 0
sp_fill_shopping_cart_clo_res 453155 453155 0 0.00 0 17941 15 0 0
sp_get_clo_for_invoice 4948 4948 0 0.00 0 24360 336 0 0
sp_kill_cost_storno 16453 16453 0 0.00 0 11602 60 0 0
sp_kill_qstorno_ret_qs2qd 1141088 1141088 0 0.00 0 48468 31 0 0
sp_kill_qty_storno 600900 600900 0 0.00 0 48470 68 0 0
sp_lock_selected_doc 20407 20407 0 0.00 0 5702 5 0 0
sp_make_cost_storno 27458 27458 0 0.00 0 27348 57 0 0
sp_multiply_rows_for_pdistr 27458 27458 0 0.00 0 8236 29 0 0
sp_multiply_rows_for_qdistr 20446 20446 0 0.00 0 46293 166 0 0
sp_pay_from_customer 6939 6939 0 0.00 4 34870 109 0 0
sp_pay_to_supplier 5820 5820 0 0.00 4 18516 126 0 0
sp_payment_common 12759 12759 0 0.00 2 34859 32 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 0 8020 347 0 0
sp_qd_handle_on_invoice_upd_sts 10614 10614 0 0.00 0 36817 332 0 0
sp_qd_handle_on_reserve_upd_sts 9751 9751 0 0.00 0 11336 50 0 0
sp_reserve_write_off 9751 9751 0 0.00 6 26413 168 0 0
sp_supplier_invoice 5506 5506 0 0.00 5 94308 3470 0 0
sp_supplier_order 5390 5390 0 0.00 5 29613 1250 0 0
srv_aggregate_perf_data 102 102 0 0.00 2150 453465 22927 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1342 192939 44654 0 0
srv_find_qd_qs_mism 1053686 1053686 0 0.00 0 12575 5 0 0
srv_make_invnt_saldo 516 516 0 0.00 41 38011 1186 0 0
srv_make_money_saldo 777 777 0 0.00 0 4549 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 238744 278774 251793 0 0
t$perf-abend:sp_add_invoice_to_stock 77 77 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4959 4959 0 0.00 0 14430 214 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 12407 154 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 1288 10 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 6615 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 1995 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 2711 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 2628 29 0 0
t$perf-norm:sp_cancel_supplier_order 443 443 0 0.00 0 53061 1818 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 2214 7 0 0
t$perf-norm:sp_client_order 20446 20446 0 0.00 0 11723 18 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 415 5 0 0
t$perf-norm:sp_pay_from_customer 6939 6939 0 0.00 0 12822 17 0 0
t$perf-norm:sp_pay_to_supplier 5820 5820 0 0.00 0 9558 14 0 0
t$perf-norm:sp_reserve_write_off 9751 9751 0 0.00 0 8699 12 0 0
t$perf-norm:sp_supplier_invoice 5506 5506 0 0.00 0 23753 27 0 0
t$perf-norm:sp_supplier_order 5390 5390 0 0.00 0 13631 22 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 215 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 105 3 0 0
t$perf-norm:srv_make_invnt_saldo 516 516 0 0.00 0 3529 30 0 0
t$perf-norm:srv_make_money_saldo 777 777 0 0.00 0 6203 32 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 11 4 0 0
v_add_invoice_to_stock 10044 9890 154 1.53 0 5736 17 0 154
v_all_customers 37240 37240 0 0.00 0 38 1 0 0
v_all_suppliers 30226 30226 0 0.00 0 25 1 0 0
v_all_wares 40892 40892 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 201 6 0 0
v_cancel_client_order 3566 3566 0 0.00 0 8029 7 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 756 4 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 575 3 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 655 4 0 0
v_cancel_supplier_invoice 772 764 8 1.03 0 343 13 0 8
v_cancel_supplier_order 886 886 0 0.00 0 410 5 0 0
v_cancel_write_off 1930 1930 0 0.00 0 938 14 0 0
v_max_id_clo_ord 5390 5390 0 0.00 0 974 2 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 1705 13 0 0
v_max_id_ord_sup 5506 5506 0 0.00 0 4761 7 0 0
v_max_non_paid_invoice 1603 1603 0 0.00 0 995 3 0 0
v_max_non_paid_realizn 3677 3677 0 0.00 0 2218 6 0 0
v_min_id_clo_ord 5390 5390 0 0.00 0 1820 5 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 5321 9 0 0
v_min_id_ord_sup 5506 5506 0 0.00 0 3819 5 0 0
v_min_non_paid_invoice 5820 5820 0 0.00 0 18494 19 0 0
v_min_non_paid_realizn 6939 6939 0 0.00 0 34853 28 0 0
v_our_firm 10176 10176 0 0.00 0 113 1 0 0
v_reserve_write_off 19502 19502 0 0.00 0 2896 6 0 0
x_lock_depdocs_on_canc_invoice 11575 11575 0 0.00 0 12806 122 0 0
x_lock_depdocs_on_canc_sup_ord 443 443 0 0.00 0 9937 382 0 0
x_make_qstorno_1000_1200 5390 5390 0 0.00 1 29111 1171 0 0
x_make_qstorno_1200_2000 5506 5506 0 0.00 2 92492 3311 0 0
x_make_qstorno_2100_3300 453161 453161 0 0.00 0 88638 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-10-2022 22:40:30 to 08-10-2022 22:40:34.

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
20:08:35 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:37 28843 8192000000 9891997248 10206052352 983452832 10.718 100 85 170 0 1012470304 6308224 22709248
20:11:14 30925 8192000000 9955915024 10278899712 1036612064 11.232 100 89 177 0 1067834752 8271680 22951008
20:12:48 12904 8192000000 9986439344 10319372288 1071758192 11.569 100 86 172 0 1102990624 9792768 21439664
20:14:04 11750 8192000000 10032580496 10382290944 1123863904 12.063 100 84 168 0 1155006160 11351296 19790960
20:15:21 5470 8192000000 10086093392 10441146368 1171183440 12.508 100 85 169 0 1204483120 13535648 19764032
20:16:31 2825 8192000000 10129783056 10492317696 1211007456 12.878 100 89 176 0 1244352224 13550144 19794624
20:17:37 2041 8192000000 10158562816 10534072320 1245708480 13.199 100 88 174 1 1277501552 12995360 18797712
20:18:41 2154 8192000000 10181696960 10560073728 1269637136 13.418 100 88 174 0 1303544928 14590016 19317776
20:19:44 1314 8192000000 10211896720 10595446784 1295328592 13.653 100 93 186 2 1330032192 13789888 20913712
20:20:47 21216 8192000000 10211324944 10609692672 1305177712 13.742 100 58 112 1 1325220480 7271952 12770816
20:22:09 4953 8192000000 10209337600 10619981824 1314145040 13.824 100 36 73 0 1329968528 6988096 8835392
20:23:20 11799 8192000000 10207420992 10627248128 1325399520 13.926 100 15 32 0 1334645856 5644832 3601504
20:24:33 17000 8192000000 10229885328 10639904768 1330516704 13.972 100 35 68 2 1343607776 5108256 7982816
20:25:53 7898 8192000000 10232948608 10649784320 1338514864 14.044 100 29 56 0 1350670464 6299136 5856464
20:27:03 4932 8192000000 10240526464 10656415744 1340710608 14.064 100 28 57 2 1351345392 4503776 6131008
20:28:08 62489 8192000000 10209035104 10625302528 1293223728 13.634 97 20 40 0 1303084048 5826784 4033536
20:30:12 4064 8192000000 10188682816 10598289408 1287184400 13.579 99 36 73 1 1301873760 6233840 8455520
20:31:17 6906 8192000000 10150662464 10551955456 1212785264 12.895 95 20 38 0 1222355280 4887632 4682384
20:32:30 21850 8192000000 10137311616 10539028480 1216133072 12.926 96 24 50 0 1227346608 5376960 5836576
20:33:52 7789 8192000000 10139573856 10541973504 1242254192 13.167 99 16 32 0 1252353376 5950672 4148512
20:35:00 6731 8192000000 10180604960 10588856320 1287737488 13.584 100 30 60 1 1299677968 4888576 7051904
20:36:08 6611 8192000000 10203561888 10609278976 1302787920 13.721 100 40 78 2 1318314160 6343008 9183232
20:37:15 14565 8192000000 10214269648 10621140992 1309940240 13.786 100 24 51 1 1320252256 5306368 5005648
20:38:30 47539 8192000000 10222696480 10638790656 1330699552 13.973 100 28 55 0 1341996000 4887552 6408896
20:40:45 8189 8192000000 10266732608 10680205312 1361942000 14.255 100 46 92 0 1382834112 10852320 10039792
20:42:03 16083 8192000000 10281346544 10700705792 1374590512 14.368 100 43 86 0 1397110128 13086560 9433056
20:43:36 22986 8192000000 10293532864 10719612928 1388689440 14.494 100 31 62 1 1408245280 12149424 7406416
20:45:08 12458 8192000000 10321655664 10744127488 1405316144 14.642 100 45 89 1 1430763328 15112768 10334416
20:46:24 9553 8192000000 10334848160 10756857856 1417079712 14.747 100 59 118 0 1448244432 17166272 13998448
20:47:51 2068 8192000000 10322322128 10748325888 1397312336 14.571 99 55 110 0 1428890800 18142496 13435968
20:48:56 3905 8192000000 10356488704 10779156480 1431014768 14.870 100 57 114 1 1464832448 20687728 13129952
20:50:11 3624 8192000000 10363007664 10779971584 1420685984 14.779 100 75 146 4 1468281296 30367632 17227680
20:51:18 3061 8192000000 10353425248 10768470016 1409249664 14.677 100 63 123 2 1458393584 33331632 15812288
20:52:29 3347 8192000000 10347587520 10766495744 1403078576 14.622 100 71 142 0 1455958832 34695440 18184816
20:53:37 1989 8192000000 10357986496 10776449024 1409330224 14.678 100 78 154 3 1464637696 35221008 20086464
20:54:41 3474 8192000000 10346080000 10763272192 1394133968 14.543 99 73 145 0 1450424208 37513328 18776912
20:55:50 2353 8192000000 10355187600 10767134720 1395024752 14.551 99 78 156 0 1447299936 33019488 19255696
20:56:58 1337 8192000000 10322715984 10743037952 1384017504 14.452 100 57 112 0 1428143984 30208384 13918096
20:58:04 2576 8192000000 10374560800 10781184000 1407042192 14.658 100 86 173 1 1470847136 42620848 21184096
20:59:16 5059 8192000000 10357692880 10775728128 1398283312 14.580 100 63 126 0 1460481168 45271776 16926080
21:00:27 5619 8192000000 10345802144 10772000768 1382870208 14.442 99 54 109 1 1448171920 50452880 14848832
21:01:35 26944 8192000000 10317766720 10745364480 1358365104 14.223 98 33 64 0 1415286272 47403888 9517280
21:03:06 18097 8192000000 10304303424 10741108736 1350730864 14.154 100 24 48 0 1409130560 51294464 7105232
21:04:27 29443 8192000000 10282469744 10721812480 1324376704 13.916 98 27 54 1 1378029376 45230640 8422032
21:06:05 18742 8192000000 10271103040 10709733376 1316971248 13.849 98 17 34 0 1368016832 45905200 5140384
21:07:36 32849 8192000000 10257248128 10685456384 1330392736 13.971 99 43 80 1 1355437248 15078832 9965680
21:09:09 36035 8192000000 10252789008 10697818112 1342220432 14.077 99 19 39 0 1355140416 8575664 4344320
21:10:47 192939 8192000000 10266263104 10715119616 1334978464 14.012 98 13 26 1 1348262032 10805616 2477952
21:15:06 14875 8192000000 10277190768 10718380032 1365261584 14.285 99 28 56 0 1381990944 9931392 6797968
21:16:21 68718 8192000000 10299565552 10759258112 1388198192 14.490 100 13 26 1 1411225280 19123664 3903424
21:18:31 100132 8192000000 10296905792 10754977792 1393673776 14.539 100 26 51 1 1425435296 25393776 6367744
21:21:41 60193 8192000000 10292894576 10749222912 1383013056 14.443 99 9 18 0 1406127488 21042208 2072224
21:23:43 34834 8192000000 10303418000 10757361664 1387674304 14.485 99 24 48 1 1405440752 11946000 5820448
21:25:29 44692 8192000000 10300211760 10767040512 1398684944 14.583 100 27 50 1 1422324416 16591696 7047776
21:29:23 39208 8192000000 10286178416 10753687552 1387122608 14.480 99 4 8 0 1394316592 6555776 638208
21:31:04 61881 8192000000 10298398832 10767384576 1387385504 14.483 99 12 24 0 1409322384 18711616 3225264
21:33:15 11118 8192000000 10288929584 10749120512 1380220432 14.419 99 35 67 1 1396441120 9105952 7114736
21:34:27 52133 8192000000 10291710656 10766860288 1396618912 14.565 100 10 20 0 1408244992 9600368 2025712
21:36:19 87368 8192000000 10302330272 10766893056 1381669632 14.431 98 15 30 0 1396119088 10867216 3582240
21:38:48 62568 8192000000 10307673776 10770993152 1380673200 14.423 98 19 38 0 1401722016 16869952 4178864
21:40:54 66055 8192000000 10297534592 10752880640 1333204016 13.996 95 10 20 0 1345806144 10863920 1738208
21:43:00 60848 8192000000 10304451280 10775543808 1393365600 14.536 99 30 59 0 1414358192 14234704 6757888
21:45:05 55697 8192000000 10288517760 10738024448 1341602880 14.072 96 22 44 0 1355491728 9205168 4683680
21:47:09 55118 8192000000 10267862224 10715631616 1319871792 13.876 96 27 54 0 1340185504 14520240 5793472
21:49:09 130726 8192000000 10254038832 10707111936 1277047920 13.486 94 8 16 0 1294729536 15736608 1945008
21:52:36 33270 8192000000 10257374848 10701250560 1355612368 14.198 100 23 44 0 1368536112 8119104 4804640
21:54:13 79936 8192000000 10262136560 10726735872 1354327888 14.186 99 9 18 0 1367841888 11470368 2043632
21:56:44 47153 8192000000 10288149232 10727526400 1360224688 14.239 99 28 56 0 1379193824 12215120 6754016
21:58:32 63034 8192000000 10263529248 10713587712 1321847520 13.893 96 25 50 0 1342080272 14339520 5893232
22:00:58 70856 8192000000 10244191504 10694963200 1290615392 13.610 96 14 26 0 1309578896 15343776 3619728
22:03:14 84167 8192000000 10244568256 10689900544 1300494048 13.700 96 13 26 0 1314755472 10975856 3285568
22:05:45 59567 8192000000 10268522544 10721095680 1332420784 13.989 98 20 40 0 1353596864 16480800 4695280
22:07:45 76625 8192000000 10282638672 10742280192 1343597584 14.090 98 16 30 1 1366075136 19056464 3421088
22:10:02 48580 8192000000 10299585584 10759757824 1389044272 14.497 100 32 64 0 1411864208 15938736 6881200
22:11:58 24791 8192000000 10321943664 10765877248 1401840208 14.611 100 28 56 0 1420356992 12351872 6164912
22:13:26 67187 8192000000 10299986352 10750918656 1381050304 14.426 99 24 48 0 1401347856 14989520 5308032
22:16:06 78624 8192000000 10297489536 10768519168 1397594560 14.574 100 6 12 0 1411683680 12833200 1255920
22:18:26 68475 8192000000 10301332928 10763374592 1405991984 14.648 100 11 22 0 1418024480 9415088 2617408
22:20:36 71843 8192000000 10304931152 10769092608 1410852944 14.692 100 30 60 0 1426981728 9405168 6723616
22:22:48 39108 8192000000 10308497232 10764845056 1401507696 14.608 100 17 30 0 1413478960 8604464 3366800
22:24:37 56956 8192000000 10317051696 10774478848 1396227024 14.561 99 23 46 0 1415636640 13827872 5581744
22:26:41 53663 8192000000 10299408368 10757410816 1372544544 14.350 98 11 22 0 1388226096 13169696 2511856
22:28:39 41319 8192000000 10321700048 10775162880 1408316496 14.669 100 24 48 0 1428594192 14457200 5820496
22:30:24 86202 8192000000 10314819104 10768580608 1358712432 14.226 96 13 26 0 1373321440 12094800 2514208
22:33:22 75128 8192000000 10303301568 10756337664 1362221536 14.257 97 7 15 0 1377149584 13650736 1277312
22:35:39 48081 8192000000 10272681984 10717286400 1314245040 13.825 95 29 58 0 1331396512 10923808 6227664
22:37:42 66778 8192000000 9648700400 10008248320 770801488 8.600 57 10 20 0 783658064 11155152 1701424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-10-2022 22:40:36 to 08-10-2022 22:40:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 77 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-10-2022 22:40:52 to 08-10-2022 22:40:54.

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          234112
MON$OLDEST_ACTIVE               234759
MON$OLDEST_SNAPSHOT             234759
MON$NEXT_TRANSACTION            234759
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                       2025760
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
:52: 
:53: mscs-test-161.budzdorov.ru    Sat Oct  8 22:39:00 2022
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:55: 
:56: 
:57: mscs-test-161.budzdorov.ru    Sat Oct  8 22:39:05 2022
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:59: 
:60: 
:61: mscs-test-161.budzdorov.ru    Sat Oct  8 22:39:05 2022
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:63: 
:64: 
:65: mscs-test-161.budzdorov.ru    Sat Oct  8 22:39:05 2022
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:67: 
--- end of diff output ---

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

08.10.22 22:40:55. 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-08 22:40:58 No severe PSQL-related problems occured
08.10.22 22:40:59. End of report.