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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15961144 kB
MemAvailable 30785048 kB
Buffers 0 kB
Cached 15704728 kB
SwapCached 492 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.8
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 2021-09-18 22:37:48
DTS_END                         2021-09-18 22:37:48.6750
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/33680
TRN_ID                          251567
ATT_ID                          657
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: 9318.75 20028 1118250
customer order: creation 182.08 327 21850
customer order: refuse 16.02 316 1923
order to supplier: creation 48.54 1271 5825
order to supplier: removal 3.68 91790 441
invoice (draft): creation 48.80 3633 5856
invoice (draft): removal 52.81 839 6337
invoice accept: apply 44.27 20067 5312
invoice accept: cancel 46.88 854 5625
customer reserve: creation 4021.85 203 482622
customer reserve: removal 4544.22 57 545306
realization accept: apply 88.95 173 10674
realization accept: cancel 84.49 96 10139
payment from customer: creation 62.47 125 7496
payment from customer: removal 5.78 63 694
payment to supplier: creation 51.74 128 6209
payment to supplier: removal 3.40 81 408
service: total inventory turnovers 4.87 1248 584
service: total monetary turnovers 7.06 35 847
service: aggregate perf. data 0.82 27071 98
service: refresh index statistics 0.03 252183 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-09-2021 22:38:51 to 18-09-2021 22:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2982
TEST_TIME 2 2888
TEST_TIME 3 4702
TEST_TIME 4 4945
TEST_TIME 5 5244
TEST_TIME 6 5472
TEST_TIME 7 5298
TEST_TIME 8 5322
TEST_TIME 9 5294
TEST_TIME 10 5475
TEST_TIME 11 6163
TEST_TIME 12 6649
TEST_TIME 13 6816
TEST_TIME 14 6896
TEST_TIME 15 7278
TEST_TIME 16 7283
TEST_TIME 17 7340
TEST_TIME 18 7534
TEST_TIME 19 7597
TEST_TIME 20 7912
TEST_TIME 21 8183
TEST_TIME 22 8292
TEST_TIME 23 8210
TEST_TIME 24 8556
TEST_TIME 25 8695
TEST_TIME 26 8936
TEST_TIME 27 9218
TEST_TIME 28 9389
TEST_TIME 29 9231
TEST_TIME 30 9398
TEST_TIME 31 9885
TEST_TIME 32 10308
TEST_TIME 33 10195
TEST_TIME 34 10243
TEST_TIME 35 10240
TEST_TIME 36 10534
TEST_TIME 37 10477
TEST_TIME 38 10260
TEST_TIME 39 10119
TEST_TIME 40 10020
TEST_TIME 41 10043
TEST_TIME 42 9957
TEST_TIME 43 10179
TEST_TIME 44 10020
TEST_TIME 45 9897
TEST_TIME 46 9868
TEST_TIME 47 9838
TEST_TIME 48 9916
TEST_TIME 49 9969
TEST_TIME 50 9870
TEST_TIME 51 9821
TEST_TIME 52 9745
TEST_TIME 53 9619
TEST_TIME 54 9555
TEST_TIME 55 9639
TEST_TIME 56 9608
TEST_TIME 57 9507
TEST_TIME 58 9482
TEST_TIME 59 9426
TEST_TIME 60 9361
TEST_TIME 61 9337
TEST_TIME 62 9381
TEST_TIME 63 9483
TEST_TIME 64 9420
TEST_TIME 65 9472
TEST_TIME 66 9577
TEST_TIME 67 9571
TEST_TIME 68 9528
TEST_TIME 69 9503
TEST_TIME 70 9661
TEST_TIME 71 9645
TEST_TIME 72 9694
TEST_TIME 73 9725
TEST_TIME 74 9631
TEST_TIME 75 9531
TEST_TIME 76 9589
TEST_TIME 77 9496
TEST_TIME 78 9524
TEST_TIME 79 9479
TEST_TIME 80 9422
TEST_TIME 81 9462
TEST_TIME 82 9428
TEST_TIME 83 9454
TEST_TIME 84 9391
TEST_TIME 85 9404
TEST_TIME 86 9384
TEST_TIME 87 9352
TEST_TIME 88 9396
TEST_TIME 89 9447
TEST_TIME 90 9464
TEST_TIME 91 9507
TEST_TIME 92 9493
TEST_TIME 93 9488
TEST_TIME 94 9490
TEST_TIME 95 9534
TEST_TIME 96 9614
TEST_TIME 97 9585
TEST_TIME 98 9600
TEST_TIME 99 9582
TEST_TIME 100 9540
TEST_TIME 101 9502
TEST_TIME 102 9463
TEST_TIME 103 9461
TEST_TIME 104 9410
TEST_TIME 105 9360
TEST_TIME 106 9401
TEST_TIME 107 9369
TEST_TIME 108 9324
TEST_TIME 109 9328
TEST_TIME 110 9282
TEST_TIME 111 9248
TEST_TIME 112 9250
TEST_TIME 113 9259
TEST_TIME 114 9294
TEST_TIME 115 9285
TEST_TIME 116 9331
TEST_TIME 117 9321
TEST_TIME 118 9331
TEST_TIME 119 9323
TEST_TIME 120 9319

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-09-2021 22:38:55 to 18-09-2021 22:38:58.

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 1565807 1565807 0 0.00 0 16027 7 0 0
doc_list_biud 1565806 1565806 0 0.00 0 33797 26 0 0
sp_add_invoice_to_stock 5446 5312 134 2.46 35 257961 20068 0 134
sp_cancel_adding_invoice 5625 5625 0 0.00 4 69601 855 0 0
sp_cancel_client_order 1923 1923 0 0.00 5 10954 317 0 0
sp_cancel_customer_reserve 545306 545306 0 0.00 1 21435 57 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 3085 64 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 3236 81 0 0
sp_cancel_supplier_invoice 6356 6337 19 0.29 2 29847 839 0 19
sp_cancel_supplier_order 441 441 0 0.00 6 1088148 91791 0 0
sp_cancel_write_off 10139 10139 0 0.00 1 7677 97 0 0
sp_client_order 21850 21850 0 0.00 3 12165 327 0 0
sp_customer_reserve 482622 482622 0 0.00 0 52415 204 0 0
sp_fill_shopping_cart 33530 33530 0 0.00 1 8412 37 0 0
sp_fill_shopping_cart_clo_res 482622 482622 0 0.00 0 14819 15 0 0
sp_get_clo_for_invoice 5312 5312 0 0.00 0 31551 258 0 0
sp_kill_cost_storno 16866 16866 0 0.00 0 7372 53 0 0
sp_kill_qstorno_ret_qs2qd 1107529 1107529 0 0.00 0 33795 31 0 0
sp_kill_qty_storno 585757 585757 0 0.00 0 33796 69 0 0
sp_lock_selected_doc 22163 22163 0 0.00 0 7665 5 0 0
sp_make_cost_storno 29691 29691 0 0.00 0 15858 52 0 0
sp_multiply_rows_for_pdistr 29690 29690 0 0.00 0 5592 27 0 0
sp_multiply_rows_for_qdistr 21850 21850 0 0.00 0 11995 143 0 0
sp_pay_from_customer 7496 7496 0 0.00 4 22517 126 0 0
sp_pay_to_supplier 6209 6209 0 0.00 4 13651 128 0 0
sp_payment_common 13704 13704 0 0.00 1 22474 39 0 0
sp_qd_handle_on_cancel_clo 1923 1923 0 0.00 0 10903 293 0 0
sp_qd_handle_on_invoice_upd_sts 10937 10937 0 0.00 0 23674 347 0 0
sp_qd_handle_on_reserve_upd_sts 10674 10674 0 0.00 0 16602 62 0 0
sp_reserve_write_off 10674 10674 0 0.00 7 16620 174 0 0
sp_supplier_invoice 5856 5856 0 0.00 6 101727 3634 0 0
sp_supplier_order 5825 5825 0 0.00 4 34064 1271 0 0
srv_aggregate_perf_data 98 98 0 0.00 2836 708177 27072 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1734 111428 40883 0 0
srv_find_qd_qs_mism 1068379 1068379 0 0.00 0 14145 6 0 0
srv_make_invnt_saldo 584 584 0 0.00 29 84154 1249 0 0
srv_make_money_saldo 847 847 0 0.00 1 6826 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 117340 447917 252184 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5312 5312 0 0.00 0 12076 186 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 10069 154 0 0
t$perf-norm:sp_cancel_client_order 1923 1923 0 0.00 0 1111 9 0 0
t$perf-norm:sp_cancel_customer_reserve 770 770 0 0.00 0 758 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 4375 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 663 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 465 465 0 0.00 0 407 14 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 22876 1770 0 0
t$perf-norm:sp_cancel_write_off 1059 1059 0 0.00 0 1970 8 0 0
t$perf-norm:sp_client_order 21850 21850 0 0.00 0 8467 22 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 3390 8 0 0
t$perf-norm:sp_pay_from_customer 7496 7496 0 0.00 0 9506 10 0 0
t$perf-norm:sp_pay_to_supplier 6209 6209 0 0.00 0 4867 11 0 0
t$perf-norm:sp_reserve_write_off 10674 10674 0 0.00 0 5050 10 0 0
t$perf-norm:sp_supplier_invoice 5856 5856 0 0.00 0 3696 17 0 0
t$perf-norm:sp_supplier_order 5825 5825 0 0.00 0 2641 17 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 281 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 572 12 0 0
t$perf-norm:srv_make_invnt_saldo 584 584 0 0.00 0 4714 35 0 0
t$perf-norm:srv_make_money_saldo 847 847 0 0.00 0 2417 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 67 21 0 0
v_add_invoice_to_stock 10892 10624 268 2.46 0 4032 20 0 268
v_all_customers 39598 39598 0 0.00 0 22 1 0 0
v_all_suppliers 32330 32330 0 0.00 0 22 1 0 0
v_all_wares 43698 43698 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 4482 11 0 0
v_cancel_client_order 3846 3846 0 0.00 0 3858 6 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 3039 6 0 0
v_cancel_customer_reserve 1540 1540 0 0.00 0 2098 5 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 3042 8 0 0
v_cancel_supplier_invoice 968 930 38 3.92 0 2564 19 0 38
v_cancel_supplier_order 882 882 0 0.00 0 2878 13 0 0
v_cancel_write_off 2118 2118 0 0.00 0 3419 16 0 0
v_max_id_clo_ord 5825 5825 0 0.00 0 1709 2 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 3740 9 0 0
v_max_id_ord_sup 5856 5856 0 0.00 0 2544 5 0 0
v_max_non_paid_invoice 1724 1724 0 0.00 0 293 2 0 0
v_max_non_paid_realizn 4121 4121 0 0.00 0 4389 7 0 0
v_min_id_clo_ord 5825 5825 0 0.00 0 2937 7 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 1158 3 0 0
v_min_id_ord_sup 5856 5856 0 0.00 0 5268 5 0 0
v_min_non_paid_invoice 6208 6208 0 0.00 0 8981 20 0 0
v_min_non_paid_realizn 7496 7496 0 0.00 0 11200 23 0 0
v_our_firm 10850 10850 0 0.00 0 19 1 0 0
v_reserve_write_off 21348 21348 0 0.00 0 6953 7 0 0
x_lock_depdocs_on_canc_invoice 11497 11497 0 0.00 0 11488 124 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 9792 328 0 0
x_make_qstorno_1000_1200 5825 5825 0 0.00 1 34054 1183 0 0
x_make_qstorno_1200_2000 5856 5856 0 0.00 3 101642 3511 0 0
x_make_qstorno_2100_3300 482622 482622 0 0.00 0 52412 186 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-09-2021 22:38:59 to 18-09-2021 22:39:03.

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:07:52 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:53 25905 8192000000 10379002032 10700505088 1420292064 14.775 100 86 172 2 1455405488 6648112 28465312
20:10:23 17397 8192000000 10430435104 10764365824 1491347936 15.401 100 88 176 1 1527756128 8476688 27931504
20:11:46 18763 8192000000 10492326816 10838757376 1547578992 15.889 100 79 158 0 1582112496 10163728 24369776
20:13:10 11483 8192000000 10587372224 10944856064 1641508128 16.693 100 83 166 0 1678384128 12286288 24589712
20:14:24 10545 8192000000 10634292672 10998390784 1688921920 17.092 100 81 161 0 1725230960 12913280 23395760
20:15:37 5293 8192000000 10684000448 11055202304 1739333856 17.513 100 89 179 0 1777950256 13471648 25144752
20:16:44 2634 8192000000 10732834784 11110211584 1791162704 17.941 100 88 172 2 1829798144 13468688 25166752
20:17:48 1334 8192000000 10757961312 11144421376 1826059120 18.227 100 90 177 1 1864501232 13897872 24544240
20:18:51 1034 8192000000 10817496464 11206868992 1873380032 18.612 100 95 190 2 1913386128 13349024 26657072
20:19:53 2081 8192000000 10802164016 11217588224 1883340288 18.692 100 48 96 0 1906044656 8905920 13798448
20:20:57 10479 8192000000 10789682608 11222528000 1881706256 18.679 100 26 52 3 1899945056 11302624 6936176
20:22:08 7582 8192000000 10798148992 11226144768 1884816256 18.704 100 19 38 1 1896258896 6002752 5439888
20:23:16 65554 8192000000 10813621824 11241398272 1893042544 18.770 100 19 38 0 1903991968 5483904 5465520
20:25:24 3685 8192000000 10825303552 11252084736 1898351616 18.813 100 32 64 1 1913773072 5702624 9718832
20:26:28 9745 8192000000 10805526432 11241500672 1877837840 18.648 99 15 32 0 1887533232 5617232 4078160
20:27:40 8457 8192000000 10769333248 11195502592 1812839600 18.119 97 13 26 1 1826295456 10008848 3447008
20:28:49 25098 8192000000 10777753520 11185647616 1830602496 18.264 99 41 82 2 1849022592 5483296 12936800
20:30:17 7663 8192000000 10696015680 11095384064 1716135664 17.320 95 25 50 1 1729175216 5460384 7579168
20:31:26 15185 8192000000 10705376448 11112386560 1725380144 17.397 96 22 44 1 1737644896 5721968 6542784
20:32:41 9548 8192000000 10739569216 11159085056 1825573472 18.223 100 18 36 2 1836826432 5847376 5405584
20:33:53 27620 8192000000 10746660224 11182051328 1846930384 18.397 100 30 60 0 1861590272 6172528 8487360
20:35:21 9243 8192000000 10791271664 11215495168 1870276880 18.587 100 24 48 0 1883315648 5405152 7633616
20:36:31 10186 8192000000 10807259952 11229671424 1880983584 18.673 100 33 64 2 1896703872 5301776 10418512
20:37:41 17140 8192000000 10839486608 11260321792 1913974672 18.939 100 24 49 0 1929566848 9017248 6574928
20:39:09 95171 8192000000 10880420176 11318927360 1950250640 19.228 100 43 86 2 1971655584 8982992 12421952
20:42:03 9558 8192000000 10900618160 11336499200 1967842464 19.368 100 39 79 1 1988930528 10275792 10812272
20:43:17 15483 8192000000 10911831424 11351519232 1980697440 19.470 100 49 98 0 2007303696 12089728 14516528
20:44:37 7437 8192000000 10927888336 11355242496 1977868208 19.448 99 62 124 0 2008514880 12992400 17654272
20:45:58 6493 8192000000 10954196608 11380846592 2005599072 19.667 100 63 124 1 2039528368 15980096 17949200
20:47:10 11299 8192000000 10924244128 11365027840 1988060880 19.528 99 49 98 0 2018851840 16407920 14383040
20:48:33 12628 8192000000 10938850272 11376918528 1995802128 19.590 100 56 112 1 2032062528 19610544 16649856
20:49:51 16941 8192000000 10919735088 11353608192 1964436368 19.341 99 67 131 1 2002766272 18385360 19944544
20:51:14 6472 8192000000 10925095232 11351949312 1971086304 19.394 100 69 136 1 2011061376 19767744 20207328
20:52:28 3309 8192000000 10876369072 11303972864 1925925312 19.034 98 56 112 1 1959476992 17322656 16229024
20:53:36 2797 8192000000 10929244672 11351531520 1971138064 19.394 100 76 153 4 2013835440 19619024 23078352
20:54:45 2033 8192000000 10914678656 11333242880 1946957648 19.202 99 73 147 1 1994046288 24491600 22597040
20:55:50 5135 8192000000 10921679488 11330060288 1950755376 19.232 100 81 160 4 2002856528 27301024 24800128
20:57:04 1732 8192000000 10949570320 11355942912 1969496224 19.381 100 88 175 2 2025215520 29786832 25932464
20:58:10 4462 8192000000 10942219936 11354255360 1946256560 19.197 99 70 140 3 2003222080 34460944 22504576
20:59:19 11269 8192000000 10888722240 11327324160 1924339312 19.022 99 32 64 0 1967623312 32457632 10826368
21:00:37 2527 8192000000 10922159280 11334311936 1925321616 19.029 98 82 160 1 1987304448 36038144 25944688
21:01:44 12058 8192000000 10902218912 11340214272 1930111920 19.068 99 36 72 0 1975878096 34129120 11637056
21:03:02 3090 8192000000 10928657040 11365924864 1965329680 19.348 100 46 90 2 2018238784 38179408 14729696
21:04:09 8447 8192000000 10925416592 11374862336 1966938064 19.361 100 44 86 3 2016050336 35510704 13601568
21:05:18 20402 8192000000 10932272912 11380502528 1968465920 19.373 100 29 58 0 2013739152 35558512 9714720
21:06:42 18995 8192000000 10939822224 11390844928 1975301808 19.427 100 31 62 2 2023702960 38375136 10026016
21:08:29 26135 8192000000 10905095392 11372908544 1949076672 19.219 100 19 38 1 2006038896 50210976 6751248
21:09:56 42900 8192000000 10927843408 11362861056 1968810032 19.376 100 33 68 0 1987267952 8319488 10138432
21:11:40 63304 8192000000 10909190832 11380154368 1967522864 19.366 100 10 20 0 1990070848 19707760 2840224
21:13:45 111427 8192000000 10925336816 11375636480 1971926560 19.401 99 15 31 0 1986801904 10765104 4110240
21:16:38 54479 8192000000 10948597136 11406249984 1996851952 19.598 100 40 80 1 2028503920 19092304 12559664
21:18:34 36237 8192000000 10950235728 11409174528 2009669952 19.699 100 38 76 1 2035110496 14625568 10814976
21:20:30 83656 8192000000 10927136976 11406909440 1981743312 19.478 99 13 26 0 2005247104 19642320 3861472
21:22:54 55814 8192000000 10930060688 11402563584 1973670096 19.415 98 21 42 1 1988650576 9073872 5906608
21:24:58 55052 8192000000 10936659728 11415846912 1999352832 19.618 100 29 58 2 2025693648 17648608 8692208
21:27:01 13540 8192000000 10903383440 11395727360 1964432672 19.341 99 6 12 1 1982692352 16765536 1494144
21:28:15 70622 8192000000 10900663920 11371864064 1930042800 19.067 97 17 34 1 1944843392 10148432 4652160
21:30:46 79633 8192000000 10909954416 11383963648 1966204512 19.355 99 11 21 0 1980151600 11143152 2803936
21:33:21 41406 8192000000 10879771024 11361333248 1929890064 19.066 97 31 63 0 1948190224 9158016 9142144
21:35:15 46990 8192000000 10853715200 11338686464 1924031424 19.019 98 21 42 1 1939634800 8892176 6711200
21:37:04 42987 8192000000 10868550032 11349598208 1934492608 19.103 99 15 30 0 1948380880 9937616 3950656
21:39:09 62672 8192000000 10908313680 11372961792 1946820352 19.201 99 18 36 0 1967296144 16269408 4206384
21:41:23 23220 8192000000 10904699824 11368083456 1958412496 19.293 99 37 74 1 1978591200 9901440 10277264
21:42:47 40823 8192000000 10887155008 11375464448 1951444496 19.238 99 19 38 1 1971927888 15072800 5410592
21:44:55 32300 8192000000 10908085680 11372806144 1940160704 19.148 98 31 62 0 1961828480 13488656 8179120
21:46:35 81039 8192000000 10860204144 11331821568 1871700432 18.598 96 22 44 0 1897037680 18760880 6576368
21:49:06 108696 8192000000 10843246656 11301236736 1850324592 18.425 95 7 14 0 1872626160 20331072 1970496
21:51:56 105373 8192000000 10849687408 11320487936 1926451744 19.038 100 45 90 0 1952256976 13025328 12779904
21:54:46 43880 8192000000 10864259024 11334148096 1941874432 19.162 100 31 63 0 1963112224 12401600 8836192
21:56:42 47835 8192000000 10875212592 11356344320 1954920224 19.266 100 8 16 0 1971591040 14255616 2415200
21:58:31 77537 8192000000 10880116112 11357388800 1944873152 19.186 99 16 32 1 1963130512 13034752 5222608
22:02:14 58306 8192000000 10848416096 11309707264 1815293600 18.139 93 18 36 1 1831441248 11206800 4940848
22:04:19 26303 8192000000 10865503360 11326513152 1933735424 19.097 100 24 48 1 1950462192 9697824 7028944
22:05:50 91325 8192000000 10889586448 11366010880 1920116768 18.988 98 14 28 0 1946137360 22312784 3707808
22:08:28 61061 8192000000 10911361968 11374489600 1943713056 19.176 98 19 38 0 1961171088 12580832 4877200
22:10:31 64093 8192000000 10904673232 11361738752 1884245616 18.699 95 28 56 0 1903711152 11694752 7770784
22:12:44 52259 8192000000 10911739152 11376594944 1962718128 19.328 99 24 48 1 1982183312 13144080 6321104
22:14:42 16149 8192000000 10931844736 11371425792 1945726832 19.192 98 41 81 0 1965067296 7686208 11654256
22:15:59 45233 8192000000 10898610480 11361320960 1937459440 19.126 98 31 62 0 1958990704 12268480 9262784
22:17:44 82036 8192000000 10865075424 11345727488 1907136960 18.884 97 17 34 0 1923391984 11208192 5046832
22:20:11 85282 8192000000 10886099808 11371483136 1936409392 19.118 98 10 20 0 1950882288 11568592 2904304
22:22:40 35156 8192000000 10892590912 11370881024 1967511008 19.366 100 14 28 0 1986127312 14506944 4109360
22:24:16 55194 8192000000 10893023088 11359174656 1929945856 19.066 98 19 38 2 1941779328 6374320 5459152
22:26:28 60983 8192000000 10847143296 11330428928 1919324832 18.981 99 8 16 0 1930646992 9243424 2078736
22:28:30 51255 8192000000 10862270960 11336716288 1908812752 18.897 98 19 38 0 1925901648 11001936 6086960
22:30:22 53519 8192000000 10877410960 11348041728 1932485696 19.087 99 20 40 1 1947383008 8957632 5939680
22:32:20 31442 8192000000 10878092976 11343343616 1915251600 18.949 98 33 66 0 1938109264 12954352 9903312
22:33:53 31887 8192000000 10862471472 11320041472 1854690672 18.460 95 28 56 0 1873445168 10924720 7829776
22:35:26 38354 8192000000 10852303264 11326050304 1931182464 19.076 100 32 64 0 1950911488 10918464 8810560
22:37:11 56844 8192000000 9815957648 10177425408 925577440 10.151 48 3 6 0 938589824 12532912 479472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-09-2021 22:39:05 to 18-09-2021 22:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-09-2021 22:39:21 to 18-09-2021 22:39:23.

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          251567
MON$OLDEST_ACTIVE               251836
MON$OLDEST_SNAPSHOT             251836
MON$NEXT_TRANSACTION            251836
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                       2042272
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33498 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33498 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33498 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33498 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 ---
:76: 
:77: mscs-test-161.budzdorov.ru    Sat Sep 18 22:38:11 2021
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Sat Sep 18 22:38:18 2021
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Sat Sep 18 22:38:19 2021
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
--- end of diff output ---

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

18.09.21 22:39:24. 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
2021-09-18 22:39:27 No severe PSQL-related problems occured
18.09.21 22:39:28. End of report.