Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.05.2022 19: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 17215644 kB
MemAvailable 31420420 kB
Buffers 0 kB
Cached 14490404 kB
SwapCached 2636 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.10
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-05-17 19:38:19
DTS_END                         2022-05-17 19:38:19.9170
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47572
TRN_ID                          235515
ATT_ID                          644
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: 9191.96 19766 1103035
customer order: creation 169.71 358 20365
customer order: refuse 14.38 351 1726
order to supplier: creation 44.98 1275 5397
order to supplier: removal 3.36 108837 403
invoice (draft): creation 44.87 3354 5384
invoice (draft): removal 52.64 782 6317
invoice accept: apply 40.94 18930 4913
invoice accept: cancel 47.65 824 5718
customer reserve: creation 3808.29 186 456995
customer reserve: removal 4681.00 62 561720
realization accept: apply 82.27 179 9872
realization accept: cancel 76.74 115 9209
payment from customer: creation 56.84 117 6821
payment from customer: removal 5.95 133 714
payment to supplier: creation 47.13 143 5656
payment to supplier: removal 3.49 86 419
service: total inventory turnovers 4.77 1180 572
service: total monetary turnovers 6.03 40 723
service: aggregate perf. data 0.89 18657 107
service: refresh index statistics 0.03 239702 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-05-2022 19:39:43 to 17-05-2022 19:39:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7521
TEST_TIME 2 5934
TEST_TIME 3 6982
TEST_TIME 4 5728
TEST_TIME 5 5290
TEST_TIME 6 5353
TEST_TIME 7 5356
TEST_TIME 8 5200
TEST_TIME 9 5686
TEST_TIME 10 5438
TEST_TIME 11 5660
TEST_TIME 12 5696
TEST_TIME 13 5623
TEST_TIME 14 5648
TEST_TIME 15 5723
TEST_TIME 16 5752
TEST_TIME 17 5986
TEST_TIME 18 6207
TEST_TIME 19 6750
TEST_TIME 20 7149
TEST_TIME 21 7479
TEST_TIME 22 8174
TEST_TIME 23 8184
TEST_TIME 24 8325
TEST_TIME 25 8335
TEST_TIME 26 8691
TEST_TIME 27 8697
TEST_TIME 28 8929
TEST_TIME 29 10373
TEST_TIME 30 10341
TEST_TIME 31 10378
TEST_TIME 32 10120
TEST_TIME 33 9972
TEST_TIME 34 10100
TEST_TIME 35 9895
TEST_TIME 36 9817
TEST_TIME 37 9694
TEST_TIME 38 9608
TEST_TIME 39 9595
TEST_TIME 40 9481
TEST_TIME 41 9536
TEST_TIME 42 9506
TEST_TIME 43 9431
TEST_TIME 44 9428
TEST_TIME 45 9301
TEST_TIME 46 9266
TEST_TIME 47 9218
TEST_TIME 48 9203
TEST_TIME 49 9074
TEST_TIME 50 9114
TEST_TIME 51 9287
TEST_TIME 52 9275
TEST_TIME 53 9184
TEST_TIME 54 9147
TEST_TIME 55 9247
TEST_TIME 56 9449
TEST_TIME 57 9409
TEST_TIME 58 9384
TEST_TIME 59 9313
TEST_TIME 60 9370
TEST_TIME 61 9652
TEST_TIME 62 9594
TEST_TIME 63 9481
TEST_TIME 64 9396
TEST_TIME 65 9336
TEST_TIME 66 9334
TEST_TIME 67 9334
TEST_TIME 68 9264
TEST_TIME 69 9209
TEST_TIME 70 9221
TEST_TIME 71 9206
TEST_TIME 72 9215
TEST_TIME 73 9193
TEST_TIME 74 9183
TEST_TIME 75 9202
TEST_TIME 76 9204
TEST_TIME 77 9168
TEST_TIME 78 9337
TEST_TIME 79 9386
TEST_TIME 80 9394
TEST_TIME 81 9346
TEST_TIME 82 9334
TEST_TIME 83 9333
TEST_TIME 84 9269
TEST_TIME 85 9258
TEST_TIME 86 9218
TEST_TIME 87 9216
TEST_TIME 88 9178
TEST_TIME 89 9126
TEST_TIME 90 9104
TEST_TIME 91 9143
TEST_TIME 92 9240
TEST_TIME 93 9239
TEST_TIME 94 9274
TEST_TIME 95 9270
TEST_TIME 96 9280
TEST_TIME 97 9296
TEST_TIME 98 9349
TEST_TIME 99 9387
TEST_TIME 100 9346
TEST_TIME 101 9345
TEST_TIME 102 9390
TEST_TIME 103 9401
TEST_TIME 104 9379
TEST_TIME 105 9331
TEST_TIME 106 9299
TEST_TIME 107 9299
TEST_TIME 108 9271
TEST_TIME 109 9290
TEST_TIME 110 9256
TEST_TIME 111 9233
TEST_TIME 112 9200
TEST_TIME 113 9196
TEST_TIME 114 9184
TEST_TIME 115 9215
TEST_TIME 116 9249
TEST_TIME 117 9211
TEST_TIME 118 9255
TEST_TIME 119 9253
TEST_TIME 120 9194

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-05-2022 19:39:46 to 17-05-2022 19:39:50.

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 1525999 1525999 0 0.00 0 24149 7 0 0
doc_list_biud 1525998 1525998 0 0.00 0 73683 29 0 0
sp_add_invoice_to_stock 5054 4913 141 2.78 33 206224 18930 0 141
sp_cancel_adding_invoice 5718 5718 0 0.00 4 52951 825 0 0
sp_cancel_client_order 1726 1726 0 0.00 4 12548 352 0 0
sp_cancel_customer_reserve 561720 561720 0 0.00 1 51909 63 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 3 14593 134 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 6621 87 0 0
sp_cancel_supplier_invoice 6322 6317 5 0.07 2 73690 782 0 5
sp_cancel_supplier_order 403 403 0 0.00 6 1149951 108838 0 0
sp_cancel_write_off 9209 9209 0 0.00 1 14671 115 0 0
sp_client_order 20365 20365 0 0.00 3 29473 358 0 0
sp_customer_reserve 456995 456995 0 0.00 0 61106 187 0 0
sp_fill_shopping_cart 31134 31134 0 0.00 1 13665 36 0 0
sp_fill_shopping_cart_clo_res 456991 456991 0 0.00 0 14032 14 0 0
sp_get_clo_for_invoice 4908 4908 0 0.00 0 24958 301 0 0
sp_kill_cost_storno 16060 16060 0 0.00 0 10885 59 0 0
sp_kill_qstorno_ret_qs2qd 1139369 1139369 0 0.00 0 73680 34 0 0
sp_kill_qty_storno 599870 599870 0 0.00 0 73682 72 0 0
sp_lock_selected_doc 20469 20469 0 0.00 0 6730 6 0 0
sp_make_cost_storno 27255 27255 0 0.00 0 18822 65 0 0
sp_multiply_rows_for_pdistr 27255 27255 0 0.00 0 9052 30 0 0
sp_multiply_rows_for_qdistr 20365 20365 0 0.00 0 28299 172 0 0
sp_pay_from_customer 6821 6821 0 0.00 4 20256 117 0 0
sp_pay_to_supplier 5656 5656 0 0.00 4 16859 143 0 0
sp_payment_common 12477 12477 0 0.00 1 8746 30 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 0 12540 314 0 0
sp_qd_handle_on_invoice_upd_sts 10624 10624 0 0.00 0 27372 322 0 0
sp_qd_handle_on_reserve_upd_sts 9871 9871 0 0.00 0 15484 49 0 0
sp_reserve_write_off 9872 9872 0 0.00 6 22349 179 0 0
sp_supplier_invoice 5384 5384 0 0.00 6 75989 3355 0 0
sp_supplier_order 5397 5397 0 0.00 5 19962 1275 0 0
srv_aggregate_perf_data 107 107 0 0.00 2516 368705 18657 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1950 111570 41375 0 0
srv_find_qd_qs_mism 1056137 1056137 0 0.00 0 15933 6 0 0
srv_make_invnt_saldo 572 572 0 0.00 44 129061 1181 0 0
srv_make_money_saldo 723 723 0 0.00 1 10363 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 103883 422479 239703 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4913 4913 0 0.00 0 18905 179 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 4083 122 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 2411 11 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 2200 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 4089 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 3861 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 2379 21 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 22152 1829 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 2316 7 0 0
t$perf-norm:sp_client_order 20365 20365 0 0.00 0 11723 19 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 830 5 0 0
t$perf-norm:sp_pay_from_customer 6821 6821 0 0.00 0 4187 9 0 0
t$perf-norm:sp_pay_to_supplier 5656 5656 0 0.00 0 8204 11 0 0
t$perf-norm:sp_reserve_write_off 9872 9872 0 0.00 0 2508 7 0 0
t$perf-norm:sp_supplier_invoice 5384 5384 0 0.00 0 5767 18 0 0
t$perf-norm:sp_supplier_order 5397 5397 0 0.00 0 3779 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 487 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1389 38 0 0
t$perf-norm:srv_make_invnt_saldo 572 572 0 0.00 0 1053 17 0 0
t$perf-norm:srv_make_money_saldo 723 723 0 0.00 0 3608 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 4 0 0
v_add_invoice_to_stock 10094 9812 282 2.79 0 7307 18 0 282
v_all_customers 36532 36532 0 0.00 0 28 0 0 0
v_all_suppliers 29808 29808 0 0.00 0 193 1 0 0
v_all_wares 40730 40730 0 0.00 0 118 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 1193 8 0 0
v_cancel_client_order 3452 3452 0 0.00 0 7958 11 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 7255 17 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 375 3 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 5343 10 0 0
v_cancel_supplier_invoice 782 772 10 1.27 0 771 12 0 10
v_cancel_supplier_order 806 806 0 0.00 0 324 3 0 0
v_cancel_write_off 1880 1880 0 0.00 0 597 10 0 0
v_max_id_clo_ord 5395 5395 0 0.00 0 396 1 0 0
v_max_id_clo_res 974 974 0 0.00 0 3372 12 0 0
v_max_id_ord_sup 5374 5374 0 0.00 0 3229 4 0 0
v_max_non_paid_invoice 1521 1521 0 0.00 0 3480 5 0 0
v_max_non_paid_realizn 3770 3770 0 0.00 0 3286 4 0 0
v_min_id_clo_ord 5395 5395 0 0.00 0 3419 8 0 0
v_min_id_clo_res 974 974 0 0.00 0 2288 5 0 0
v_min_id_ord_sup 5374 5374 0 0.00 0 5518 5 0 0
v_min_non_paid_invoice 5656 5656 0 0.00 0 7235 20 0 0
v_min_non_paid_realizn 6821 6821 0 0.00 0 8737 25 0 0
v_our_firm 10300 10300 0 0.00 0 18 1 0 0
v_reserve_write_off 19742 19742 0 0.00 0 6514 7 0 0
x_lock_depdocs_on_canc_invoice 11649 11649 0 0.00 0 13492 117 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 7942 350 0 0
x_make_qstorno_1000_1200 5397 5397 0 0.00 2 19951 1195 0 0
x_make_qstorno_1200_2000 5384 5384 0 0.00 2 75978 3225 0 0
x_make_qstorno_2100_3300 456995 456995 0 0.00 0 61105 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-05-2022 19:39:51 to 17-05-2022 19:39:55.

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
17:08:23 2 8192000000 8801767216 8813973504 7793088 0.095 2 2 3 0 7934176 66144 74944
17:09:24 30011 8192000000 9911204400 10222329856 986287152 10.745 100 79 158 0 1012689024 5692896 20708976
17:10:59 13519 8192000000 9953389856 10276433920 1036308080 11.229 100 87 174 0 1066152800 7485936 22358784
17:12:24 23906 8192000000 10002964944 10339045376 1078513952 11.633 100 83 166 0 1108073008 8806144 20752912
17:14:03 12621 8192000000 10049789952 10400071680 1135023632 12.169 100 83 166 1 1165432384 10882096 19526656
17:15:20 7297 8192000000 10100678064 10462257152 1184384656 12.631 100 82 164 0 1215958976 13087104 18487216
17:16:30 4395 8192000000 10135865408 10504130560 1219489360 12.957 100 87 172 0 1252807984 14469792 18848832
17:17:37 4901 8192000000 10165834064 10543001600 1251217040 13.249 100 84 168 3 1283881632 14752640 17911952
17:18:44 3078 8192000000 10200058448 10578661376 1280400464 13.517 100 89 177 1 1313377248 13251120 19725664
17:19:49 1508 8192000000 10222485696 10605584384 1303357760 13.726 100 87 174 0 1335914096 12616112 19940224
17:20:52 20313 8192000000 10226099664 10616999936 1310455632 13.790 100 73 146 0 1339219104 11443040 17320432
17:22:12 6091 8192000000 10212998352 10620063744 1315499680 13.836 100 24 47 3 1326086016 5396816 5189520
17:23:20 8888 8192000000 10213084512 10627792896 1323547120 13.909 100 27 54 1 1344760160 15382496 5830544
17:24:31 13449 8192000000 10212375264 10633613312 1329781696 13.965 100 14 26 3 1338384352 6053552 2549104
17:25:47 44849 8192000000 10242295760 10642415616 1324043872 13.913 99 63 128 1 1345867344 5698128 16125344
17:27:32 6088 8192000000 10239163984 10652368896 1324690528 13.919 99 36 72 2 1338956880 5877696 8388656
17:28:39 8385 8192000000 10207204592 10618949632 1291860624 13.621 98 22 45 1 1302820848 5907600 5052624
17:29:53 12575 8192000000 10187119744 10602913792 1301949792 13.713 100 24 46 2 1312949264 5408480 5590992
17:31:09 23067 8192000000 10124374544 10524909568 1123574496 12.061 88 19 38 0 1132466224 4598544 4293184
17:32:32 6334 8192000000 10155547408 10547585024 1242832768 13.172 99 27 54 0 1255407408 5745344 6829296
17:33:41 10788 8192000000 10135630240 10530537472 1215604384 12.921 96 15 30 0 1228570816 9366448 3599984
17:34:52 12548 8192000000 10177194960 10584346624 1282106960 13.532 100 25 50 2 1293745344 5191808 6446576
17:36:05 22867 8192000000 10173631328 10589278208 1287760912 13.584 100 26 52 1 1299243984 5596240 5886832
17:37:32 7285 8192000000 10197209232 10610843648 1302701792 13.720 100 29 58 1 1316083424 6637216 6744416
17:38:42 7629 8192000000 10238747616 10648338432 1335000928 14.012 100 40 80 0 1350746576 7328144 8417504
17:39:53 4342 8192000000 10259956336 10669457408 1349553216 14.143 100 60 118 1 1373259792 10381792 13324784
17:41:02 14362 8192000000 10267593408 10691829760 1359730272 14.235 100 39 78 2 1378413328 9786832 8896224
17:42:27 21427 8192000000 10287533520 10707726336 1379902080 14.416 100 46 92 2 1401284176 11049984 10332112
17:44:05 20190 8192000000 10297409088 10723831808 1384040624 14.453 100 45 90 3 1409506704 14976448 10489632
17:45:28 16400 8192000000 10315882736 10736009216 1392408096 14.527 100 56 111 1 1422692896 16934144 13350656
17:46:47 19725 8192000000 10317154608 10741473280 1396091152 14.560 100 49 98 1 1428823744 20901168 11831424
17:48:12 14300 8192000000 10333390880 10761973760 1414236464 14.722 100 48 96 0 1448775232 22859728 11679040
17:49:34 10519 8192000000 10331170720 10751725568 1406989168 14.657 99 56 112 1 1441164224 20392624 13782432
17:50:56 13203 8192000000 10346207744 10770743296 1421063728 14.782 100 64 128 0 1461627456 24533984 16029744
17:52:17 3802 8192000000 10341809072 10763706368 1409633296 14.681 100 56 111 2 1452424912 28492256 14299360
17:53:24 4729 8192000000 10355746896 10773282816 1417631120 14.752 100 64 128 3 1465793312 31836048 16326144
17:54:37 4413 8192000000 10362899072 10779545600 1407209056 14.659 99 63 124 1 1460368464 37160800 15998608
17:55:48 8112 8192000000 10369773840 10789793792 1416115840 14.738 100 61 121 2 1476413056 43402608 16894608
17:57:05 1950 8192000000 10372324912 10780504064 1404966224 14.639 100 82 165 0 1475279648 49554912 20758512
17:58:13 6330 8192000000 10373608576 10790019072 1404373296 14.634 100 67 133 1 1472479408 49646592 18459520
17:59:25 5396 8192000000 10369468736 10781642752 1393662624 14.539 100 83 166 0 1471127312 55547136 21917552
18:00:34 4228 8192000000 10369573104 10784342016 1396329872 14.562 100 72 145 1 1470267776 55757456 18180448
18:01:40 18572 8192000000 10342683504 10773508096 1390316656 14.509 99 38 76 1 1441452736 40407680 10728400
18:03:01 19024 8192000000 10327834928 10757132288 1364489056 14.278 98 28 56 1 1415678208 43263584 7925568
18:04:39 43328 8192000000 10300916128 10745634816 1351749184 14.163 99 21 42 2 1404031616 46156624 6125808
18:06:24 33597 8192000000 10299199744 10737631232 1324951504 13.922 99 17 34 1 1386266144 55930432 5384208
18:07:59 40218 8192000000 10269030624 10708836352 1353062000 14.175 100 10 20 1 1369471504 14361216 2048288
18:10:44 108608 8192000000 10264676752 10717585408 1332118112 13.986 97 25 50 0 1345649520 8328688 5202720
18:13:33 67719 8192000000 10244809152 10684125184 1302398656 13.717 95 24 48 0 1314530128 7028800 5102672
18:16:03 28507 8192000000 10314651088 10747723776 1379158176 14.409 100 41 81 0 1396032224 8444160 8429888
18:17:39 48933 8192000000 10295893776 10750181376 1381869728 14.433 99 23 46 2 1403013792 15948816 5195248
18:19:29 26833 8192000000 10285925168 10747920384 1375460880 14.376 99 17 34 0 1393014496 13363984 4189632
18:21:22 15823 8192000000 10291854736 10747961344 1378993488 14.408 99 13 26 0 1391612272 9789104 2829680
18:22:55 68450 8192000000 10287971648 10748977152 1391883408 14.523 100 15 30 1 1405526640 9638048 4005184
18:25:10 33490 8192000000 10297155072 10749386752 1386871712 14.478 99 19 38 0 1402616688 11339760 4405216
18:26:45 44611 8192000000 10281869440 10748235776 1387840032 14.487 100 12 24 0 1401690336 11262384 2587920
18:28:49 63497 8192000000 10296336832 10781159424 1376660848 14.387 99 6 12 0 1400655072 22702304 1291920
18:30:53 44967 8192000000 10323210208 10773094400 1404907552 14.639 100 10 20 1 1418688000 11579968 2200480
18:32:42 62369 8192000000 10328458480 10796126208 1401381328 14.607 99 39 78 0 1430178256 20007296 8789632
18:35:45 49642 8192000000 10310709120 10766520320 1377557328 14.395 98 15 30 0 1394686192 14110736 3018128
18:37:40 22584 8192000000 10343492064 10784350208 1404199936 14.632 99 56 111 1 1434458272 17691568 12566768
18:39:02 51153 8192000000 10313612144 10795327488 1383020496 14.444 98 11 22 1 1412941408 27536720 2384192
18:41:24 22064 8192000000 10311361232 10758471680 1383881056 14.451 99 54 111 0 1405358864 8938960 12538848
18:43:19 39895 8192000000 10303175056 10753253376 1348622096 14.135 96 28 56 0 1369269728 14377744 6269888
18:45:06 49329 8192000000 10259158208 10717945856 1319405568 13.871 95 23 47 2 1332585104 6839456 6340080
18:47:02 53271 8192000000 10287728560 10745315328 1390556128 14.511 100 14 28 0 1402574624 8743184 3275312
18:48:58 67626 8192000000 10283924560 10754617344 1393086848 14.533 100 16 32 0 1409010720 11386480 4537392
18:51:16 83711 8192000000 10264069296 10717429760 1281784832 13.529 93 11 22 0 1291999120 7886096 2328192
18:53:42 37245 8192000000 10271920960 10724605952 1353921440 14.183 99 25 50 0 1371010608 11685008 5404160
18:55:20 42360 8192000000 10291697616 10741174272 1364382688 14.277 99 22 44 0 1387450784 18106688 4961408
18:58:29 66004 8192000000 10277438144 10705657856 1276763120 13.483 93 27 53 1 1294248000 11466944 6017936
19:00:48 18119 8192000000 10281641440 10712174592 1338909136 14.048 98 50 98 3 1360790048 11176640 10704272
19:02:08 19065 8192000000 10293575088 10720628736 1366858336 14.299 100 49 98 0 1390785776 13512368 10415072
19:03:35 46064 8192000000 10265445152 10714877952 1358002352 14.219 99 17 34 0 1370631280 8187232 4441696
19:05:21 30314 8192000000 10248278656 10710388736 1349677968 14.145 99 3 6 0 1359105552 8963968 463616
19:06:56 80165 8192000000 10285763952 10735783936 1355071072 14.193 99 19 38 0 1373760048 14730400 3958576
19:09:17 60773 8192000000 10296193104 10747686912 1348714512 14.136 98 14 28 1 1374679120 22439520 3525088
19:11:18 109648 8192000000 10289960896 10745786368 1370069104 14.328 99 9 18 0 1392678720 21015360 1594256
19:14:16 65217 8192000000 10288959344 10740969472 1338225248 14.041 96 25 50 1 1352271872 8322320 5724304
19:16:26 111570 8192000000 10324858960 10798714880 1376575632 14.386 98 11 22 0 1404154128 25326320 2252176
19:19:55 72464 8192000000 10307661696 10767884288 1373933472 14.362 98 15 30 0 1393477152 15724432 3819248
19:22:08 110518 8192000000 10295955904 10756329472 1382332464 14.437 99 12 24 0 1399088112 13446256 3309392
19:25:02 15790 8192000000 10311064880 10766340096 1402131632 14.614 100 36 71 1 1421147504 11271584 7744288
19:26:20 74592 8192000000 10303223152 10773647360 1398841088 14.585 99 10 21 0 1414196304 12718864 2636352
19:28:36 55049 8192000000 10289642048 10759409664 1389035696 14.497 99 22 45 1 1403479536 9593840 4850000
19:30:34 74519 8192000000 10297738240 10758406144 1378008928 14.399 98 14 28 0 1388966816 7979280 2978608
19:32:48 86638 8192000000 10285927616 10745753600 1338071104 14.040 96 20 40 0 1353619024 10964720 4583200
19:35:55 82905 8192000000 10302778048 10764824576 1373385568 14.357 98 19 38 0 1394388400 16665936 4336896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-05-2022 19:39:57 to 17-05-2022 19:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-05-2022 19:40:12 to 17-05-2022 19:40:14.

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          233096
MON$OLDEST_ACTIVE               235631
MON$OLDEST_SNAPSHOT             235631
MON$NEXT_TRANSACTION            235631
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                       2026080
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33592 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33592 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33592 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33592 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Tue May 17 19:38:23 2022
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Tue May 17 19:38:34 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Tue May 17 19:38:35 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Tue May 17 19:38:37 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Tue May 17 19:38:43 2022
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Tue May 17 19:38:46 2022
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Tue May 17 19:38:47 2022
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:38: 
--- end of diff output ---

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

17.05.22 19:40:15. 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-05-17 19:40:18 No severe PSQL-related problems occured
17.05.22 19:40:19. End of report.